Sep 12 17:36:21.985113 kernel: Linux version 6.12.47-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Sep 12 15:34:39 -00 2025 Sep 12 17:36:21.985151 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=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858 Sep 12 17:36:21.985165 kernel: BIOS-provided physical RAM map: Sep 12 17:36:21.985174 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000002ffff] usable Sep 12 17:36:21.985182 kernel: BIOS-e820: [mem 0x0000000000030000-0x000000000004ffff] reserved Sep 12 17:36:21.985191 kernel: BIOS-e820: [mem 0x0000000000050000-0x000000000009efff] usable Sep 12 17:36:21.985201 kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved Sep 12 17:36:21.985210 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009b8ecfff] usable Sep 12 17:36:21.985222 kernel: BIOS-e820: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Sep 12 17:36:21.985231 kernel: BIOS-e820: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Sep 12 17:36:21.985239 kernel: BIOS-e820: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Sep 12 17:36:21.985250 kernel: BIOS-e820: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Sep 12 17:36:21.985259 kernel: BIOS-e820: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Sep 12 17:36:21.985268 kernel: BIOS-e820: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Sep 12 17:36:21.985279 kernel: BIOS-e820: [mem 0x000000009bfb7000-0x000000009bffffff] usable Sep 12 17:36:21.985288 kernel: BIOS-e820: [mem 0x000000009c000000-0x000000009cffffff] reserved Sep 12 17:36:21.985302 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 12 17:36:21.985312 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 12 17:36:21.985321 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 12 17:36:21.985329 kernel: NX (Execute Disable) protection: active Sep 12 17:36:21.985338 kernel: APIC: Static calls initialized Sep 12 17:36:21.985347 kernel: e820: update [mem 0x9a13f018-0x9a148c57] usable ==> usable Sep 12 17:36:21.985356 kernel: e820: update [mem 0x9a102018-0x9a13ee57] usable ==> usable Sep 12 17:36:21.985365 kernel: extended physical RAM map: Sep 12 17:36:21.985374 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000002ffff] usable Sep 12 17:36:21.985384 kernel: reserve setup_data: [mem 0x0000000000030000-0x000000000004ffff] reserved Sep 12 17:36:21.985393 kernel: reserve setup_data: [mem 0x0000000000050000-0x000000000009efff] usable Sep 12 17:36:21.985404 kernel: reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] reserved Sep 12 17:36:21.985412 kernel: reserve setup_data: [mem 0x0000000000100000-0x000000009a102017] usable Sep 12 17:36:21.985421 kernel: reserve setup_data: [mem 0x000000009a102018-0x000000009a13ee57] usable Sep 12 17:36:21.985430 kernel: reserve setup_data: [mem 0x000000009a13ee58-0x000000009a13f017] usable Sep 12 17:36:21.985439 kernel: reserve setup_data: [mem 0x000000009a13f018-0x000000009a148c57] usable Sep 12 17:36:21.985448 kernel: reserve setup_data: [mem 0x000000009a148c58-0x000000009b8ecfff] usable Sep 12 17:36:21.985457 kernel: reserve setup_data: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Sep 12 17:36:21.985477 kernel: reserve setup_data: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Sep 12 17:36:21.985486 kernel: reserve setup_data: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Sep 12 17:36:21.985495 kernel: reserve setup_data: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Sep 12 17:36:21.985504 kernel: reserve setup_data: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Sep 12 17:36:21.985515 kernel: reserve setup_data: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Sep 12 17:36:21.985524 kernel: reserve setup_data: [mem 0x000000009bfb7000-0x000000009bffffff] usable Sep 12 17:36:21.985538 kernel: reserve setup_data: [mem 0x000000009c000000-0x000000009cffffff] reserved Sep 12 17:36:21.985547 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 12 17:36:21.985556 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 12 17:36:21.985565 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 12 17:36:21.985576 kernel: efi: EFI v2.7 by EDK II Sep 12 17:36:21.985586 kernel: efi: SMBIOS=0x9b9d5000 ACPI=0x9bb7e000 ACPI 2.0=0x9bb7e014 MEMATTR=0x9a1af018 RNG=0x9bb73018 Sep 12 17:36:21.985595 kernel: random: crng init done Sep 12 17:36:21.985618 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Sep 12 17:36:21.985627 kernel: secureboot: Secure boot enabled Sep 12 17:36:21.985636 kernel: SMBIOS 2.8 present. Sep 12 17:36:21.985646 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Sep 12 17:36:21.985674 kernel: DMI: Memory slots populated: 1/1 Sep 12 17:36:21.985685 kernel: Hypervisor detected: KVM Sep 12 17:36:21.985694 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 12 17:36:21.985704 kernel: kvm-clock: using sched offset of 9165670977 cycles Sep 12 17:36:21.985717 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 12 17:36:21.985727 kernel: tsc: Detected 2794.750 MHz processor Sep 12 17:36:21.985737 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 17:36:21.985746 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 17:36:21.985756 kernel: last_pfn = 0x9c000 max_arch_pfn = 0x400000000 Sep 12 17:36:21.985765 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 12 17:36:21.985784 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 17:36:21.985797 kernel: Using GB pages for direct mapping Sep 12 17:36:21.985808 kernel: ACPI: Early table checksum verification disabled Sep 12 17:36:21.985840 kernel: ACPI: RSDP 0x000000009BB7E014 000024 (v02 BOCHS ) Sep 12 17:36:21.985850 kernel: ACPI: XSDT 0x000000009BB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 12 17:36:21.985860 kernel: ACPI: FACP 0x000000009BB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:36:21.985870 kernel: ACPI: DSDT 0x000000009BB7A000 002237 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:36:21.985880 kernel: ACPI: FACS 0x000000009BBDD000 000040 Sep 12 17:36:21.985889 kernel: ACPI: APIC 0x000000009BB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:36:21.985900 kernel: ACPI: HPET 0x000000009BB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:36:21.985909 kernel: ACPI: MCFG 0x000000009BB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:36:21.985919 kernel: ACPI: WAET 0x000000009BB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:36:21.985932 kernel: ACPI: BGRT 0x000000009BB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 12 17:36:21.985944 kernel: ACPI: Reserving FACP table memory at [mem 0x9bb79000-0x9bb790f3] Sep 12 17:36:21.985954 kernel: ACPI: Reserving DSDT table memory at [mem 0x9bb7a000-0x9bb7c236] Sep 12 17:36:21.985963 kernel: ACPI: Reserving FACS table memory at [mem 0x9bbdd000-0x9bbdd03f] Sep 12 17:36:21.985973 kernel: ACPI: Reserving APIC table memory at [mem 0x9bb78000-0x9bb7808f] Sep 12 17:36:21.985982 kernel: ACPI: Reserving HPET table memory at [mem 0x9bb77000-0x9bb77037] Sep 12 17:36:21.985991 kernel: ACPI: Reserving MCFG table memory at [mem 0x9bb76000-0x9bb7603b] Sep 12 17:36:21.986000 kernel: ACPI: Reserving WAET table memory at [mem 0x9bb75000-0x9bb75027] Sep 12 17:36:21.986012 kernel: ACPI: Reserving BGRT table memory at [mem 0x9bb74000-0x9bb74037] Sep 12 17:36:21.986021 kernel: No NUMA configuration found Sep 12 17:36:21.986030 kernel: Faking a node at [mem 0x0000000000000000-0x000000009bffffff] Sep 12 17:36:21.986039 kernel: NODE_DATA(0) allocated [mem 0x9bf57dc0-0x9bf5efff] Sep 12 17:36:21.986048 kernel: Zone ranges: Sep 12 17:36:21.986057 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 17:36:21.986066 kernel: DMA32 [mem 0x0000000001000000-0x000000009bffffff] Sep 12 17:36:21.986075 kernel: Normal empty Sep 12 17:36:21.986084 kernel: Device empty Sep 12 17:36:21.986093 kernel: Movable zone start for each node Sep 12 17:36:21.986105 kernel: Early memory node ranges Sep 12 17:36:21.986115 kernel: node 0: [mem 0x0000000000001000-0x000000000002ffff] Sep 12 17:36:21.986125 kernel: node 0: [mem 0x0000000000050000-0x000000000009efff] Sep 12 17:36:21.986135 kernel: node 0: [mem 0x0000000000100000-0x000000009b8ecfff] Sep 12 17:36:21.986145 kernel: node 0: [mem 0x000000009bbff000-0x000000009bfb0fff] Sep 12 17:36:21.986155 kernel: node 0: [mem 0x000000009bfb7000-0x000000009bffffff] Sep 12 17:36:21.986165 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009bffffff] Sep 12 17:36:21.986175 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 17:36:21.986184 kernel: On node 0, zone DMA: 32 pages in unavailable ranges Sep 12 17:36:21.986196 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 12 17:36:21.986206 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Sep 12 17:36:21.986215 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Sep 12 17:36:21.986225 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges Sep 12 17:36:21.986234 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 12 17:36:21.986243 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 12 17:36:21.986254 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 12 17:36:21.986263 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 12 17:36:21.986273 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 12 17:36:21.986288 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 17:36:21.986297 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 12 17:36:21.986307 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 12 17:36:21.986316 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 17:36:21.986325 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 12 17:36:21.986334 kernel: TSC deadline timer available Sep 12 17:36:21.986343 kernel: CPU topo: Max. logical packages: 1 Sep 12 17:36:21.986352 kernel: CPU topo: Max. logical dies: 1 Sep 12 17:36:21.986365 kernel: CPU topo: Max. dies per package: 1 Sep 12 17:36:21.986383 kernel: CPU topo: Max. threads per core: 1 Sep 12 17:36:21.986394 kernel: CPU topo: Num. cores per package: 4 Sep 12 17:36:21.986404 kernel: CPU topo: Num. threads per package: 4 Sep 12 17:36:21.986417 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 12 17:36:21.986430 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 12 17:36:21.986440 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 12 17:36:21.986450 kernel: kvm-guest: setup PV sched yield Sep 12 17:36:21.986460 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Sep 12 17:36:21.986482 kernel: Booting paravirtualized kernel on KVM Sep 12 17:36:21.986493 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 17:36:21.986504 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 12 17:36:21.986515 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 12 17:36:21.986525 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 12 17:36:21.986535 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 12 17:36:21.986546 kernel: kvm-guest: PV spinlocks enabled Sep 12 17:36:21.986556 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 17:36:21.986568 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=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858 Sep 12 17:36:21.986582 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 17:36:21.986592 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 17:36:21.986602 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 17:36:21.986611 kernel: Fallback order for Node 0: 0 Sep 12 17:36:21.986621 kernel: Built 1 zonelists, mobility grouping on. Total pages: 638054 Sep 12 17:36:21.986631 kernel: Policy zone: DMA32 Sep 12 17:36:21.986649 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 17:36:21.986659 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 12 17:36:21.986672 kernel: ftrace: allocating 40125 entries in 157 pages Sep 12 17:36:21.986682 kernel: ftrace: allocated 157 pages with 5 groups Sep 12 17:36:21.986693 kernel: Dynamic Preempt: voluntary Sep 12 17:36:21.986703 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 17:36:21.986714 kernel: rcu: RCU event tracing is enabled. Sep 12 17:36:21.986724 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 12 17:36:21.986735 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 17:36:21.986745 kernel: Rude variant of Tasks RCU enabled. Sep 12 17:36:21.986756 kernel: Tracing variant of Tasks RCU enabled. Sep 12 17:36:21.986766 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 17:36:21.986780 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 12 17:36:21.986790 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:36:21.986800 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:36:21.986839 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:36:21.986861 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 12 17:36:21.986871 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 17:36:21.986881 kernel: Console: colour dummy device 80x25 Sep 12 17:36:21.986891 kernel: printk: legacy console [ttyS0] enabled Sep 12 17:36:21.986901 kernel: ACPI: Core revision 20240827 Sep 12 17:36:21.986916 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 12 17:36:21.986931 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 17:36:21.986942 kernel: x2apic enabled Sep 12 17:36:21.986952 kernel: APIC: Switched APIC routing to: physical x2apic Sep 12 17:36:21.986962 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 12 17:36:21.986972 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 12 17:36:21.986982 kernel: kvm-guest: setup PV IPIs Sep 12 17:36:21.986992 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 12 17:36:21.987002 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 12 17:36:21.987016 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Sep 12 17:36:21.987026 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 12 17:36:21.987037 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 12 17:36:21.987047 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 12 17:36:21.987077 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 17:36:21.987087 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 17:36:21.987098 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 17:36:21.987108 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 12 17:36:21.987120 kernel: active return thunk: retbleed_return_thunk Sep 12 17:36:21.987130 kernel: RETBleed: Mitigation: untrained return thunk Sep 12 17:36:21.987140 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 12 17:36:21.987150 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 12 17:36:21.987161 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 12 17:36:21.987171 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 12 17:36:21.987182 kernel: active return thunk: srso_return_thunk Sep 12 17:36:21.987192 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 12 17:36:21.987202 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 17:36:21.987215 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 17:36:21.987225 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 17:36:21.987235 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 17:36:21.987245 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 12 17:36:21.987255 kernel: Freeing SMP alternatives memory: 32K Sep 12 17:36:21.987265 kernel: pid_max: default: 32768 minimum: 301 Sep 12 17:36:21.987275 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 12 17:36:21.987286 kernel: landlock: Up and running. Sep 12 17:36:21.987296 kernel: SELinux: Initializing. Sep 12 17:36:21.987308 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 17:36:21.987318 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 17:36:21.987329 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 12 17:36:21.987339 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 12 17:36:21.987349 kernel: ... version: 0 Sep 12 17:36:21.987362 kernel: ... bit width: 48 Sep 12 17:36:21.987372 kernel: ... generic registers: 6 Sep 12 17:36:21.987382 kernel: ... value mask: 0000ffffffffffff Sep 12 17:36:21.987392 kernel: ... max period: 00007fffffffffff Sep 12 17:36:21.987404 kernel: ... fixed-purpose events: 0 Sep 12 17:36:21.987414 kernel: ... event mask: 000000000000003f Sep 12 17:36:21.987424 kernel: signal: max sigframe size: 1776 Sep 12 17:36:21.987435 kernel: rcu: Hierarchical SRCU implementation. Sep 12 17:36:21.987445 kernel: rcu: Max phase no-delay instances is 400. Sep 12 17:36:21.987456 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 12 17:36:21.987475 kernel: smp: Bringing up secondary CPUs ... Sep 12 17:36:21.987485 kernel: smpboot: x86: Booting SMP configuration: Sep 12 17:36:21.987495 kernel: .... node #0, CPUs: #1 #2 #3 Sep 12 17:36:21.987508 kernel: smp: Brought up 1 node, 4 CPUs Sep 12 17:36:21.987519 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Sep 12 17:36:21.987529 kernel: Memory: 2409224K/2552216K available (14336K kernel code, 2432K rwdata, 9960K rodata, 54040K init, 2924K bss, 137064K reserved, 0K cma-reserved) Sep 12 17:36:21.987540 kernel: devtmpfs: initialized Sep 12 17:36:21.987550 kernel: x86/mm: Memory block size: 128MB Sep 12 17:36:21.987561 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bb7f000-0x9bbfefff] (524288 bytes) Sep 12 17:36:21.987571 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bfb5000-0x9bfb6fff] (8192 bytes) Sep 12 17:36:21.987582 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 17:36:21.987592 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 12 17:36:21.987605 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 17:36:21.987615 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 17:36:21.987625 kernel: audit: initializing netlink subsys (disabled) Sep 12 17:36:21.987635 kernel: audit: type=2000 audit(1757698577.628:1): state=initialized audit_enabled=0 res=1 Sep 12 17:36:21.987646 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 17:36:21.987656 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 17:36:21.987666 kernel: cpuidle: using governor menu Sep 12 17:36:21.987676 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 17:36:21.987689 kernel: dca service started, version 1.12.1 Sep 12 17:36:21.987700 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Sep 12 17:36:21.987710 kernel: PCI: Using configuration type 1 for base access Sep 12 17:36:21.987720 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 17:36:21.987730 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 17:36:21.987740 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 17:36:21.987751 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 17:36:21.987761 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 17:36:21.987771 kernel: ACPI: Added _OSI(Module Device) Sep 12 17:36:21.987784 kernel: ACPI: Added _OSI(Processor Device) Sep 12 17:36:21.987794 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 17:36:21.987804 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 17:36:21.987827 kernel: ACPI: Interpreter enabled Sep 12 17:36:21.987838 kernel: ACPI: PM: (supports S0 S5) Sep 12 17:36:21.987848 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 17:36:21.987858 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 17:36:21.987868 kernel: PCI: Using E820 reservations for host bridge windows Sep 12 17:36:21.987878 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 12 17:36:21.987892 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 12 17:36:21.988194 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 12 17:36:21.988342 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 12 17:36:21.988495 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 12 17:36:21.988509 kernel: PCI host bridge to bus 0000:00 Sep 12 17:36:21.988703 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 12 17:36:21.988883 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 12 17:36:21.989028 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 12 17:36:21.989153 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Sep 12 17:36:21.989278 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Sep 12 17:36:21.989403 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Sep 12 17:36:21.989540 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 12 17:36:21.990006 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 12 17:36:21.990187 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 12 17:36:21.990329 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Sep 12 17:36:21.990479 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Sep 12 17:36:21.990620 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Sep 12 17:36:21.990758 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 12 17:36:21.991650 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 12 17:36:21.991801 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Sep 12 17:36:21.991985 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Sep 12 17:36:21.992126 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref] Sep 12 17:36:21.992294 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 12 17:36:21.992440 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Sep 12 17:36:21.992597 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Sep 12 17:36:21.992738 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref] Sep 12 17:36:21.993160 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 12 17:36:21.993319 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Sep 12 17:36:21.993460 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Sep 12 17:36:21.993614 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref] Sep 12 17:36:21.993752 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Sep 12 17:36:21.994093 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 12 17:36:21.994240 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 12 17:36:21.994400 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 12 17:36:21.994564 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Sep 12 17:36:21.994707 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Sep 12 17:36:21.994893 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 12 17:36:21.995039 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Sep 12 17:36:21.995054 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 12 17:36:21.995064 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 12 17:36:21.995075 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 12 17:36:21.995090 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 12 17:36:21.995100 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 12 17:36:21.995111 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 12 17:36:21.995121 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 12 17:36:21.995132 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 12 17:36:21.995142 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 12 17:36:21.995152 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 12 17:36:21.995162 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 12 17:36:21.995172 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 12 17:36:21.995184 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 12 17:36:21.995194 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 12 17:36:21.995204 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 12 17:36:21.995215 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 12 17:36:21.995225 kernel: iommu: Default domain type: Translated Sep 12 17:36:21.995235 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 17:36:21.995246 kernel: efivars: Registered efivars operations Sep 12 17:36:21.995256 kernel: PCI: Using ACPI for IRQ routing Sep 12 17:36:21.995266 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 12 17:36:21.995280 kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff] Sep 12 17:36:21.995290 kernel: e820: reserve RAM buffer [mem 0x9a102018-0x9bffffff] Sep 12 17:36:21.995300 kernel: e820: reserve RAM buffer [mem 0x9a13f018-0x9bffffff] Sep 12 17:36:21.995310 kernel: e820: reserve RAM buffer [mem 0x9b8ed000-0x9bffffff] Sep 12 17:36:21.995321 kernel: e820: reserve RAM buffer [mem 0x9bfb1000-0x9bffffff] Sep 12 17:36:21.995481 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 12 17:36:21.995627 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 12 17:36:21.995770 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 12 17:36:21.995788 kernel: vgaarb: loaded Sep 12 17:36:21.995799 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 12 17:36:21.995826 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 12 17:36:21.995837 kernel: clocksource: Switched to clocksource kvm-clock Sep 12 17:36:21.995848 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 17:36:21.995860 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 17:36:21.995872 kernel: pnp: PnP ACPI init Sep 12 17:36:21.996057 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Sep 12 17:36:21.996074 kernel: pnp: PnP ACPI: found 6 devices Sep 12 17:36:21.996089 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 17:36:21.996100 kernel: NET: Registered PF_INET protocol family Sep 12 17:36:21.996111 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 12 17:36:21.996121 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 12 17:36:21.996132 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 17:36:21.996143 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 12 17:36:21.996154 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 12 17:36:21.996164 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 12 17:36:21.996177 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 17:36:21.996188 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 17:36:21.996198 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 17:36:21.996208 kernel: NET: Registered PF_XDP protocol family Sep 12 17:36:21.996357 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Sep 12 17:36:21.996515 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Sep 12 17:36:21.996655 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 12 17:36:21.996790 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 12 17:36:21.996940 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 12 17:36:21.997080 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Sep 12 17:36:21.997237 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Sep 12 17:36:21.997371 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Sep 12 17:36:21.997386 kernel: PCI: CLS 0 bytes, default 64 Sep 12 17:36:21.997397 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 12 17:36:21.997407 kernel: Initialise system trusted keyrings Sep 12 17:36:21.997418 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 12 17:36:21.997428 kernel: Key type asymmetric registered Sep 12 17:36:21.997443 kernel: Asymmetric key parser 'x509' registered Sep 12 17:36:21.997482 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 12 17:36:21.997496 kernel: io scheduler mq-deadline registered Sep 12 17:36:21.997507 kernel: io scheduler kyber registered Sep 12 17:36:21.997518 kernel: io scheduler bfq registered Sep 12 17:36:21.997529 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 17:36:21.997540 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 12 17:36:21.997551 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 12 17:36:21.997562 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 12 17:36:21.997576 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 17:36:21.997588 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 17:36:21.997599 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 12 17:36:21.997610 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 12 17:36:21.997620 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 12 17:36:21.997783 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 12 17:36:21.997800 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 12 17:36:21.997955 kernel: rtc_cmos 00:04: registered as rtc0 Sep 12 17:36:21.998093 kernel: rtc_cmos 00:04: setting system clock to 2025-09-12T17:36:21 UTC (1757698581) Sep 12 17:36:21.998224 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Sep 12 17:36:21.998239 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 12 17:36:21.998250 kernel: efifb: probing for efifb Sep 12 17:36:21.998261 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Sep 12 17:36:21.998271 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Sep 12 17:36:21.998282 kernel: efifb: scrolling: redraw Sep 12 17:36:21.998293 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 17:36:21.998303 kernel: Console: switching to colour frame buffer device 160x50 Sep 12 17:36:21.998318 kernel: fb0: EFI VGA frame buffer device Sep 12 17:36:21.998331 kernel: pstore: Using crash dump compression: deflate Sep 12 17:36:21.998342 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 17:36:21.998353 kernel: NET: Registered PF_INET6 protocol family Sep 12 17:36:21.998364 kernel: Segment Routing with IPv6 Sep 12 17:36:21.998374 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 17:36:21.998387 kernel: NET: Registered PF_PACKET protocol family Sep 12 17:36:21.998398 kernel: Key type dns_resolver registered Sep 12 17:36:21.998409 kernel: IPI shorthand broadcast: enabled Sep 12 17:36:21.998420 kernel: sched_clock: Marking stable (4474003199, 214805380)->(4768882954, -80074375) Sep 12 17:36:21.998430 kernel: registered taskstats version 1 Sep 12 17:36:21.998441 kernel: Loading compiled-in X.509 certificates Sep 12 17:36:21.998452 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: f1ae8d6e9bfae84d90f4136cf098b0465b2a5bd7' Sep 12 17:36:21.998473 kernel: Demotion targets for Node 0: null Sep 12 17:36:21.998487 kernel: Key type .fscrypt registered Sep 12 17:36:21.998500 kernel: Key type fscrypt-provisioning registered Sep 12 17:36:21.998510 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 17:36:21.998521 kernel: ima: Allocated hash algorithm: sha1 Sep 12 17:36:21.998531 kernel: ima: No architecture policies found Sep 12 17:36:21.998542 kernel: clk: Disabling unused clocks Sep 12 17:36:21.998552 kernel: Warning: unable to open an initial console. Sep 12 17:36:21.998563 kernel: Freeing unused kernel image (initmem) memory: 54040K Sep 12 17:36:21.998574 kernel: Write protecting the kernel read-only data: 24576k Sep 12 17:36:21.998587 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 12 17:36:21.998597 kernel: Run /init as init process Sep 12 17:36:21.998608 kernel: with arguments: Sep 12 17:36:21.998618 kernel: /init Sep 12 17:36:21.998629 kernel: with environment: Sep 12 17:36:21.998639 kernel: HOME=/ Sep 12 17:36:21.998649 kernel: TERM=linux Sep 12 17:36:21.998659 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 17:36:21.998675 systemd[1]: Successfully made /usr/ read-only. Sep 12 17:36:21.998693 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 17:36:21.998705 systemd[1]: Detected virtualization kvm. Sep 12 17:36:21.998716 systemd[1]: Detected architecture x86-64. Sep 12 17:36:21.998727 systemd[1]: Running in initrd. Sep 12 17:36:21.998738 systemd[1]: No hostname configured, using default hostname. Sep 12 17:36:21.998749 systemd[1]: Hostname set to . Sep 12 17:36:21.998761 systemd[1]: Initializing machine ID from VM UUID. Sep 12 17:36:21.998775 systemd[1]: Queued start job for default target initrd.target. Sep 12 17:36:21.998786 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:36:21.998797 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:36:21.998825 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 17:36:21.998838 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:36:21.998849 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 17:36:21.998862 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 17:36:21.998878 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 12 17:36:21.998890 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 17:36:21.998902 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:36:21.998913 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:36:21.998924 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:36:21.998938 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:36:21.998950 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:36:21.998961 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:36:21.998976 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:36:21.998988 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:36:21.998999 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 17:36:21.999011 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 17:36:21.999022 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:36:21.999034 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:36:21.999045 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:36:21.999057 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:36:21.999068 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 17:36:21.999083 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:36:21.999094 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 17:36:21.999106 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 12 17:36:21.999118 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 17:36:21.999129 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:36:21.999140 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:36:21.999151 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:21.999162 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 17:36:21.999175 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:36:21.999186 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 17:36:21.999202 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:36:21.999283 systemd-journald[220]: Collecting audit messages is disabled. Sep 12 17:36:21.999317 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:21.999329 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:36:21.999341 systemd-journald[220]: Journal started Sep 12 17:36:21.999372 systemd-journald[220]: Runtime Journal (/run/log/journal/be5d641e07714407831470c84f0585d3) is 6M, max 48.2M, 42.2M free. Sep 12 17:36:21.984003 systemd-modules-load[221]: Inserted module 'overlay' Sep 12 17:36:22.001768 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:36:22.008082 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:36:22.017746 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:36:22.020936 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:36:22.028840 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 12 17:36:22.030843 kernel: Bridge firewalling registered Sep 12 17:36:22.030874 systemd-modules-load[221]: Inserted module 'br_netfilter' Sep 12 17:36:22.033196 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:36:22.035310 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:36:22.037403 systemd-tmpfiles[242]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 12 17:36:22.039514 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 17:36:22.054881 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:36:22.070573 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:36:22.078246 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:36:22.085728 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:36:22.090428 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:36:22.093634 dracut-cmdline[255]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858 Sep 12 17:36:22.157142 systemd-resolved[272]: Positive Trust Anchors: Sep 12 17:36:22.157168 systemd-resolved[272]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:36:22.157206 systemd-resolved[272]: 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 Sep 12 17:36:22.160022 systemd-resolved[272]: Defaulting to hostname 'linux'. Sep 12 17:36:22.166732 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:36:22.168342 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:36:22.257878 kernel: SCSI subsystem initialized Sep 12 17:36:22.267854 kernel: Loading iSCSI transport class v2.0-870. Sep 12 17:36:22.301863 kernel: iscsi: registered transport (tcp) Sep 12 17:36:22.332040 kernel: iscsi: registered transport (qla4xxx) Sep 12 17:36:22.332136 kernel: QLogic iSCSI HBA Driver Sep 12 17:36:22.358077 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:36:22.381183 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:36:22.385935 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:36:22.457023 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 17:36:22.462133 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 17:36:22.568916 kernel: raid6: avx2x4 gen() 27101 MB/s Sep 12 17:36:22.585870 kernel: raid6: avx2x2 gen() 29332 MB/s Sep 12 17:36:22.603164 kernel: raid6: avx2x1 gen() 19788 MB/s Sep 12 17:36:22.603262 kernel: raid6: using algorithm avx2x2 gen() 29332 MB/s Sep 12 17:36:22.621163 kernel: raid6: .... xor() 14748 MB/s, rmw enabled Sep 12 17:36:22.621281 kernel: raid6: using avx2x2 recovery algorithm Sep 12 17:36:22.645864 kernel: xor: automatically using best checksumming function avx Sep 12 17:36:22.898167 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 17:36:22.909683 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:36:22.914779 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:36:22.949991 systemd-udevd[471]: Using default interface naming scheme 'v255'. Sep 12 17:36:22.997029 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:36:23.001223 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 17:36:23.037435 dracut-pre-trigger[482]: rd.md=0: removing MD RAID activation Sep 12 17:36:23.069401 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:36:23.079180 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:36:23.169319 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:36:23.209868 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 12 17:36:23.216528 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 17:36:23.301969 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 12 17:36:23.302224 kernel: libata version 3.00 loaded. Sep 12 17:36:23.302241 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 17:36:23.302255 kernel: AES CTR mode by8 optimization enabled Sep 12 17:36:23.302269 kernel: ahci 0000:00:1f.2: version 3.0 Sep 12 17:36:23.302500 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 12 17:36:23.328886 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:36:23.333849 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 12 17:36:23.338743 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 12 17:36:23.339108 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 12 17:36:23.339320 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 12 17:36:23.341974 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:36:23.348005 kernel: scsi host0: ahci Sep 12 17:36:23.348257 kernel: scsi host1: ahci Sep 12 17:36:23.342296 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:23.351987 kernel: scsi host2: ahci Sep 12 17:36:23.352215 kernel: scsi host3: ahci Sep 12 17:36:23.345428 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:23.352396 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:23.360843 kernel: scsi host4: ahci Sep 12 17:36:23.360190 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:36:23.371320 kernel: scsi host5: ahci Sep 12 17:36:23.371548 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 lpm-pol 1 Sep 12 17:36:23.371562 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 lpm-pol 1 Sep 12 17:36:23.371572 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 lpm-pol 1 Sep 12 17:36:23.371583 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 lpm-pol 1 Sep 12 17:36:23.371594 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 lpm-pol 1 Sep 12 17:36:23.371604 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 lpm-pol 1 Sep 12 17:36:23.386743 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 12 17:36:23.421750 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 12 17:36:23.423395 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 12 17:36:23.434551 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 12 17:36:23.445799 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 17:36:23.449696 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 17:36:23.451046 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:36:23.451112 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:23.454233 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:23.468192 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:23.468715 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:36:23.493911 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:23.535879 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:36:23.543888 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:36:23.677374 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 12 17:36:23.677478 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 12 17:36:23.677848 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 12 17:36:23.678851 kernel: ata3.00: LPM support broken, forcing max_power Sep 12 17:36:23.678925 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 12 17:36:23.680052 kernel: ata3.00: applying bridge limits Sep 12 17:36:23.680856 kernel: ata3.00: LPM support broken, forcing max_power Sep 12 17:36:23.681850 kernel: ata3.00: configured for UDMA/100 Sep 12 17:36:23.683861 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 12 17:36:23.685844 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 12 17:36:23.686851 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 12 17:36:23.686868 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 12 17:36:23.742875 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 12 17:36:23.743269 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 17:36:23.768931 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 12 17:36:24.173988 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 17:36:24.176072 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:36:24.178072 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:36:24.178314 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:36:24.179979 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 17:36:24.210046 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:36:24.581859 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:36:24.582923 disk-uuid[643]: The operation has completed successfully. Sep 12 17:36:24.619663 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 17:36:24.619791 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 17:36:24.661189 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 17:36:24.684895 sh[671]: Success Sep 12 17:36:24.704096 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 17:36:24.704176 kernel: device-mapper: uevent: version 1.0.3 Sep 12 17:36:24.705430 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 12 17:36:24.715843 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 12 17:36:24.752800 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 17:36:24.756713 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 17:36:24.777850 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 17:36:24.783876 kernel: BTRFS: device fsid 74707491-1b86-4926-8bdb-c533ce2a0c32 devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (683) Sep 12 17:36:24.786648 kernel: BTRFS info (device dm-0): first mount of filesystem 74707491-1b86-4926-8bdb-c533ce2a0c32 Sep 12 17:36:24.786699 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:36:24.792852 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 17:36:24.792902 kernel: BTRFS info (device dm-0): enabling free space tree Sep 12 17:36:24.794614 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 17:36:24.795422 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 12 17:36:24.798009 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 17:36:24.799160 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 17:36:24.803324 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 17:36:24.835859 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (715) Sep 12 17:36:24.837842 kernel: BTRFS info (device vda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:36:24.837878 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:36:24.842313 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:36:24.842412 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:36:24.848845 kernel: BTRFS info (device vda6): last unmount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:36:24.849991 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 17:36:24.853875 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 17:36:25.101242 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:36:25.106015 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:36:25.109864 ignition[759]: Ignition 2.21.0 Sep 12 17:36:25.109885 ignition[759]: Stage: fetch-offline Sep 12 17:36:25.109951 ignition[759]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:36:25.109964 ignition[759]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:36:25.110113 ignition[759]: parsed url from cmdline: "" Sep 12 17:36:25.110118 ignition[759]: no config URL provided Sep 12 17:36:25.110125 ignition[759]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:36:25.110138 ignition[759]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:36:25.110172 ignition[759]: op(1): [started] loading QEMU firmware config module Sep 12 17:36:25.110179 ignition[759]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 12 17:36:25.119020 ignition[759]: op(1): [finished] loading QEMU firmware config module Sep 12 17:36:25.120072 ignition[759]: parsing config with SHA512: 592b7559b1098c7297163350fc3a0de9ccfbc212cb28e905647beae5ebfcde341b222d1ef21059bc7d8cc099bd2983171216532703fdd0fbdf6dffb75781177a Sep 12 17:36:25.131713 unknown[759]: fetched base config from "system" Sep 12 17:36:25.131732 unknown[759]: fetched user config from "qemu" Sep 12 17:36:25.131997 ignition[759]: fetch-offline: fetch-offline passed Sep 12 17:36:25.132142 ignition[759]: Ignition finished successfully Sep 12 17:36:25.135794 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:36:25.152508 systemd-networkd[860]: lo: Link UP Sep 12 17:36:25.152519 systemd-networkd[860]: lo: Gained carrier Sep 12 17:36:25.154867 systemd-networkd[860]: Enumeration completed Sep 12 17:36:25.155028 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:36:25.155334 systemd-networkd[860]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:36:25.155339 systemd-networkd[860]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:36:25.158083 systemd[1]: Reached target network.target - Network. Sep 12 17:36:25.158410 systemd-networkd[860]: eth0: Link UP Sep 12 17:36:25.158782 systemd-networkd[860]: eth0: Gained carrier Sep 12 17:36:25.158836 systemd-networkd[860]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:36:25.160136 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 12 17:36:25.161417 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 17:36:25.186919 systemd-networkd[860]: eth0: DHCPv4 address 10.0.0.3/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 17:36:25.211170 ignition[866]: Ignition 2.21.0 Sep 12 17:36:25.211567 ignition[866]: Stage: kargs Sep 12 17:36:25.212024 ignition[866]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:36:25.212035 ignition[866]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:36:25.212623 ignition[866]: kargs: kargs passed Sep 12 17:36:25.212677 ignition[866]: Ignition finished successfully Sep 12 17:36:25.216544 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 17:36:25.220113 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 17:36:25.267955 ignition[875]: Ignition 2.21.0 Sep 12 17:36:25.267972 ignition[875]: Stage: disks Sep 12 17:36:25.268185 ignition[875]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:36:25.268200 ignition[875]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:36:25.270151 ignition[875]: disks: disks passed Sep 12 17:36:25.270273 ignition[875]: Ignition finished successfully Sep 12 17:36:25.273140 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 17:36:25.277600 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 17:36:25.280219 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 17:36:25.281727 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:36:25.284052 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:36:25.284124 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:36:25.285736 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 17:36:25.324832 systemd-fsck[885]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 12 17:36:25.445878 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 17:36:25.453865 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 17:36:25.737861 kernel: EXT4-fs (vda9): mounted filesystem 26739aba-b0be-4ce3-bfbd-ca4dbcbe2426 r/w with ordered data mode. Quota mode: none. Sep 12 17:36:25.738516 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 17:36:25.739241 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 17:36:25.792388 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:36:25.794614 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 17:36:25.795861 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 12 17:36:25.795919 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 17:36:25.795952 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:36:25.812664 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 17:36:25.814798 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 17:36:25.833466 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (894) Sep 12 17:36:25.833520 kernel: BTRFS info (device vda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:36:25.833531 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:36:25.838174 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:36:25.838245 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:36:25.840037 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:36:26.004864 initrd-setup-root[918]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 17:36:26.010846 initrd-setup-root[925]: cut: /sysroot/etc/group: No such file or directory Sep 12 17:36:26.017412 initrd-setup-root[932]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 17:36:26.023343 initrd-setup-root[939]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 17:36:26.202044 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 17:36:26.206531 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 17:36:26.210654 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 17:36:26.229725 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 17:36:26.231343 kernel: BTRFS info (device vda6): last unmount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:36:26.251109 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 17:36:26.283874 ignition[1008]: INFO : Ignition 2.21.0 Sep 12 17:36:26.283874 ignition[1008]: INFO : Stage: mount Sep 12 17:36:26.286389 ignition[1008]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:36:26.286389 ignition[1008]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:36:26.289039 ignition[1008]: INFO : mount: mount passed Sep 12 17:36:26.289039 ignition[1008]: INFO : Ignition finished successfully Sep 12 17:36:26.291293 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 17:36:26.295199 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 17:36:26.327716 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:36:26.359399 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1020) Sep 12 17:36:26.359483 kernel: BTRFS info (device vda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:36:26.359497 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:36:26.366291 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:36:26.366394 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:36:26.369466 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:36:26.424898 ignition[1037]: INFO : Ignition 2.21.0 Sep 12 17:36:26.424898 ignition[1037]: INFO : Stage: files Sep 12 17:36:26.428340 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:36:26.428340 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:36:26.428340 ignition[1037]: DEBUG : files: compiled without relabeling support, skipping Sep 12 17:36:26.428340 ignition[1037]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 17:36:26.428340 ignition[1037]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 17:36:26.435279 ignition[1037]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 17:36:26.435279 ignition[1037]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 17:36:26.435279 ignition[1037]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 17:36:26.434031 unknown[1037]: wrote ssh authorized keys file for user: core Sep 12 17:36:26.513565 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:36:26.516078 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:36:26.516078 ignition[1037]: INFO : files: op(4): [started] processing unit "docker.service" Sep 12 17:36:26.516078 ignition[1037]: INFO : files: op(4): [finished] processing unit "docker.service" Sep 12 17:36:26.516078 ignition[1037]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Sep 12 17:36:26.552325 ignition[1037]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 17:36:26.935458 ignition[1037]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 17:36:26.935458 ignition[1037]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Sep 12 17:36:26.935458 ignition[1037]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Sep 12 17:36:27.133066 systemd-networkd[860]: eth0: Gained IPv6LL Sep 12 17:36:27.262944 ignition[1037]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 17:36:27.269834 ignition[1037]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 17:36:27.271770 ignition[1037]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Sep 12 17:36:27.271770 ignition[1037]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Sep 12 17:36:27.271770 ignition[1037]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Sep 12 17:36:27.271770 ignition[1037]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:36:27.271770 ignition[1037]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:36:27.271770 ignition[1037]: INFO : files: files passed Sep 12 17:36:27.271770 ignition[1037]: INFO : Ignition finished successfully Sep 12 17:36:27.284635 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 17:36:27.291598 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 17:36:27.294428 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 17:36:27.316125 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 17:36:27.316306 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 17:36:27.320208 initrd-setup-root-after-ignition[1066]: grep: /sysroot/oem/oem-release: No such file or directory Sep 12 17:36:27.326445 initrd-setup-root-after-ignition[1068]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:36:27.326445 initrd-setup-root-after-ignition[1068]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:36:27.330227 initrd-setup-root-after-ignition[1072]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:36:27.334364 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:36:27.337747 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 17:36:27.342134 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 17:36:27.416850 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 17:36:27.417041 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 17:36:27.420080 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 17:36:27.422410 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 17:36:27.424686 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 17:36:27.426054 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 17:36:27.468753 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:36:27.471095 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 17:36:27.496019 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:36:27.496294 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:36:27.501209 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 17:36:27.503428 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 17:36:27.503629 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:36:27.506923 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 17:36:27.507257 systemd[1]: Stopped target basic.target - Basic System. Sep 12 17:36:27.507656 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 17:36:27.508035 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:36:27.508434 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 17:36:27.508793 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 12 17:36:27.509385 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 17:36:27.510170 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:36:27.510690 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 17:36:27.511256 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 17:36:27.511671 systemd[1]: Stopped target swap.target - Swaps. Sep 12 17:36:27.512216 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 17:36:27.512415 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:36:27.534667 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:36:27.534882 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:36:27.535416 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 17:36:27.535612 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:36:27.543579 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 17:36:27.543768 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 17:36:27.546525 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 17:36:27.546708 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:36:27.548526 systemd[1]: Stopped target paths.target - Path Units. Sep 12 17:36:27.554227 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 17:36:27.560000 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:36:27.562568 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 17:36:27.565892 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 17:36:27.566934 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 17:36:27.567056 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:36:27.569295 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 17:36:27.569444 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:36:27.571166 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 17:36:27.571292 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:36:27.574078 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 17:36:27.575120 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 17:36:27.587940 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 17:36:27.589400 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 17:36:27.591604 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 17:36:27.591990 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:36:27.596829 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 17:36:27.596995 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:36:27.604396 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 17:36:27.604570 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 17:36:27.628386 ignition[1092]: INFO : Ignition 2.21.0 Sep 12 17:36:27.628386 ignition[1092]: INFO : Stage: umount Sep 12 17:36:27.631575 ignition[1092]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:36:27.631575 ignition[1092]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:36:27.628998 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 17:36:27.636251 ignition[1092]: INFO : umount: umount passed Sep 12 17:36:27.637476 ignition[1092]: INFO : Ignition finished successfully Sep 12 17:36:27.640106 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 17:36:27.640283 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 17:36:27.645883 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 17:36:27.646063 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 17:36:27.651644 systemd[1]: Stopped target network.target - Network. Sep 12 17:36:27.651758 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 17:36:27.651891 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 17:36:27.655102 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 17:36:27.655209 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 17:36:27.656122 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 17:36:27.656179 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 17:36:27.659257 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 17:36:27.659332 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 17:36:27.661399 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 17:36:27.661454 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 17:36:27.663801 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 17:36:27.666074 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 17:36:27.677304 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 17:36:27.677500 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 17:36:27.683211 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 17:36:27.683621 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 17:36:27.683783 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 17:36:27.690041 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 17:36:27.690916 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 12 17:36:27.706136 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 17:36:27.706229 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:36:27.710348 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 17:36:27.711454 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 17:36:27.711529 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:36:27.714109 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 17:36:27.714160 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:36:27.718034 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 17:36:27.718104 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 17:36:27.719201 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 17:36:27.719274 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:36:27.723868 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:36:27.731461 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 17:36:27.731569 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:36:27.743125 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 17:36:27.748247 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:36:27.769775 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 17:36:27.769930 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 17:36:27.772694 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 17:36:27.772790 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 17:36:27.774385 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 17:36:27.774439 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:36:27.775600 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 17:36:27.775655 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:36:27.778367 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 17:36:27.778420 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 17:36:27.782980 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:36:27.783043 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:36:27.787291 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 17:36:27.788565 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 12 17:36:27.788620 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:36:27.792484 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 17:36:27.792557 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:36:27.795847 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 12 17:36:27.795916 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:36:27.799526 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 17:36:27.799577 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:36:27.802097 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:36:27.802149 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:27.811062 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 17:36:27.811198 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 17:36:27.815587 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 12 17:36:27.815659 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 12 17:36:27.815708 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 17:36:27.815759 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:36:27.816038 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 17:36:27.817970 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 17:36:27.855327 systemd[1]: Switching root. Sep 12 17:36:27.911751 systemd-journald[220]: Journal stopped Sep 12 17:36:30.375021 systemd-journald[220]: Received SIGTERM from PID 1 (systemd). Sep 12 17:36:30.375128 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 17:36:30.375163 kernel: SELinux: policy capability open_perms=1 Sep 12 17:36:30.375179 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 17:36:30.375202 kernel: SELinux: policy capability always_check_network=0 Sep 12 17:36:30.375217 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 17:36:30.375232 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 17:36:30.375247 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 17:36:30.375272 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 17:36:30.375296 kernel: SELinux: policy capability userspace_initial_context=0 Sep 12 17:36:30.375320 kernel: audit: type=1403 audit(1757698588.496:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 17:36:30.375337 systemd[1]: Successfully loaded SELinux policy in 189.896ms. Sep 12 17:36:30.375359 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.513ms. Sep 12 17:36:30.375376 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 17:36:30.375392 systemd[1]: Detected virtualization kvm. Sep 12 17:36:30.375407 systemd[1]: Detected architecture x86-64. Sep 12 17:36:30.375422 systemd[1]: Detected first boot. Sep 12 17:36:30.375438 systemd[1]: Initializing machine ID from VM UUID. Sep 12 17:36:30.375453 zram_generator::config[1140]: No configuration found. Sep 12 17:36:30.375478 kernel: Guest personality initialized and is inactive Sep 12 17:36:30.375494 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 12 17:36:30.375516 kernel: Initialized host personality Sep 12 17:36:30.375530 kernel: NET: Registered PF_VSOCK protocol family Sep 12 17:36:30.375794 systemd[1]: Populated /etc with preset unit settings. Sep 12 17:36:30.375811 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 17:36:30.375844 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 17:36:30.375860 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 17:36:30.375883 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 17:36:30.375900 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 17:36:30.375916 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 17:36:30.375931 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 17:36:30.375947 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 17:36:30.375968 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 17:36:30.375994 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 17:36:30.376011 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 17:36:30.376031 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 17:36:30.376054 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:36:30.376071 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:36:30.376086 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 17:36:30.376102 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 17:36:30.376119 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 17:36:30.376135 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:36:30.376150 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 17:36:30.376179 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:36:30.376208 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:36:30.376224 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 17:36:30.376244 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 17:36:30.376277 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 17:36:30.376294 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 17:36:30.376311 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:36:30.376327 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:36:30.376347 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:36:30.376363 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:36:30.376386 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 17:36:30.376402 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 17:36:30.376418 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 17:36:30.376433 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:36:30.376448 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:36:30.376463 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:36:30.376478 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 17:36:30.376494 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 17:36:30.376510 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 17:36:30.376533 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 17:36:30.376550 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:30.376566 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 17:36:30.376599 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 17:36:30.376615 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 17:36:30.376638 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). Sep 12 17:36:30.376655 systemd[1]: Reached target machines.target - Containers. Sep 12 17:36:30.376673 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 17:36:30.376697 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:36:30.376717 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:36:30.376733 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 17:36:30.376749 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:36:30.376764 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:36:30.376780 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:36:30.376795 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 17:36:30.376811 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:36:30.376859 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 17:36:30.376884 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 17:36:30.376900 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 17:36:30.376916 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 17:36:30.376932 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 17:36:30.376949 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:36:30.376965 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:36:30.376980 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:36:30.377008 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:36:30.377037 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 17:36:30.377054 kernel: loop: module loaded Sep 12 17:36:30.377152 systemd-journald[1201]: Collecting audit messages is disabled. Sep 12 17:36:30.377185 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 17:36:30.377203 systemd-journald[1201]: Journal started Sep 12 17:36:30.377242 systemd-journald[1201]: Runtime Journal (/run/log/journal/be5d641e07714407831470c84f0585d3) is 6M, max 48.2M, 42.2M free. Sep 12 17:36:29.832665 systemd[1]: Queued start job for default target multi-user.target. Sep 12 17:36:29.851394 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 12 17:36:29.851986 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 17:36:30.387243 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:36:30.387378 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 17:36:30.387403 systemd[1]: Stopped verity-setup.service. Sep 12 17:36:30.387428 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:30.400093 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:36:30.397805 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 17:36:30.400026 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 17:36:30.401623 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 17:36:30.404013 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 17:36:30.411143 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 17:36:30.412676 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 17:36:30.414132 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:36:30.417040 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 17:36:30.417344 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 17:36:30.418967 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:36:30.419365 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:36:30.420892 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:36:30.421237 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:36:30.423475 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:36:30.423858 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:36:30.425492 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:36:30.446129 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 17:36:30.473087 kernel: fuse: init (API version 7.41) Sep 12 17:36:30.472703 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:36:30.474925 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:36:30.478325 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:36:30.482159 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 17:36:30.482488 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 17:36:30.494546 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 17:36:30.505494 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:36:30.508799 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 17:36:30.511601 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 17:36:30.518038 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:36:30.524127 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 17:36:30.528013 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 17:36:30.528055 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:36:30.530120 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 17:36:30.542859 kernel: ACPI: bus type drm_connector registered Sep 12 17:36:30.546401 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 17:36:30.548350 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:36:30.551664 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 17:36:30.554737 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 17:36:30.556068 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:36:30.558186 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 17:36:30.560006 systemd-tmpfiles[1227]: ACLs are not supported, ignoring. Sep 12 17:36:30.560022 systemd-tmpfiles[1227]: ACLs are not supported, ignoring. Sep 12 17:36:30.570223 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 17:36:30.575899 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:36:30.576497 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:36:30.579382 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:36:30.581194 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 17:36:30.597430 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:36:30.617960 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:36:30.696305 systemd-journald[1201]: Time spent on flushing to /var/log/journal/be5d641e07714407831470c84f0585d3 is 20.047ms for 1026 entries. Sep 12 17:36:30.696305 systemd-journald[1201]: System Journal (/var/log/journal/be5d641e07714407831470c84f0585d3) is 8M, max 195.6M, 187.6M free. Sep 12 17:36:31.413928 systemd-journald[1201]: Received client request to flush runtime journal. Sep 12 17:36:31.413973 kernel: loop0: detected capacity change from 0 to 128016 Sep 12 17:36:31.394162 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 17:36:31.396493 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 17:36:31.400684 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 17:36:31.417069 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 17:36:31.436876 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 17:36:31.468896 kernel: loop1: detected capacity change from 0 to 111000 Sep 12 17:36:31.507529 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 17:36:31.514523 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 17:36:31.518868 kernel: loop2: detected capacity change from 0 to 128016 Sep 12 17:36:31.531840 kernel: loop3: detected capacity change from 0 to 111000 Sep 12 17:36:31.540672 (sd-merge)[1277]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 12 17:36:31.541222 (sd-merge)[1277]: Merged extensions into '/usr'. Sep 12 17:36:31.545610 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 17:36:31.551081 systemd[1]: Starting ensure-sysext.service... Sep 12 17:36:31.610383 systemd[1]: Reload requested from client PID 1280 ('systemctl') (unit ensure-sysext.service)... Sep 12 17:36:31.610407 systemd[1]: Reloading... Sep 12 17:36:31.926863 zram_generator::config[1308]: No configuration found. Sep 12 17:36:32.286737 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 17:36:32.287117 systemd[1]: Reloading finished in 676 ms. Sep 12 17:36:32.329810 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 17:36:32.332647 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 17:36:32.369510 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:36:32.376461 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:36:32.380197 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:32.380496 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:36:32.387677 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:36:32.392035 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:36:32.394577 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:36:32.396135 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:36:32.396494 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:36:32.396755 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:32.403226 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:36:32.403594 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:36:32.407178 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:36:32.407447 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:36:32.413887 systemd-tmpfiles[1347]: ACLs are not supported, ignoring. Sep 12 17:36:32.413903 systemd-tmpfiles[1347]: ACLs are not supported, ignoring. Sep 12 17:36:32.426246 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 12 17:36:32.426730 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 12 17:36:32.427141 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 17:36:32.427213 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:36:32.427940 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 17:36:32.429097 systemd-tmpfiles[1348]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 17:36:32.429301 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:36:32.429476 systemd-tmpfiles[1348]: ACLs are not supported, ignoring. Sep 12 17:36:32.429516 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:36:32.429562 systemd-tmpfiles[1348]: ACLs are not supported, ignoring. Sep 12 17:36:32.435569 systemd-tmpfiles[1348]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:36:32.435585 systemd-tmpfiles[1348]: Skipping /boot Sep 12 17:36:32.438374 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:32.438675 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:36:32.442290 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:36:32.445478 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:36:32.449413 systemd-tmpfiles[1348]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:36:32.449437 systemd-tmpfiles[1348]: Skipping /boot Sep 12 17:36:32.451069 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:36:32.452442 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:36:32.452615 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:36:32.452787 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:32.479417 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:32.479931 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:36:32.484360 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:36:32.485633 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:36:32.485868 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:36:32.486069 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:36:32.487686 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:36:32.488042 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:36:32.490071 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:36:32.490368 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:36:32.492412 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:36:32.492638 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:36:32.494563 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:36:32.494811 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:36:32.500341 systemd[1]: Finished ensure-sysext.service. Sep 12 17:36:32.505682 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:36:32.505782 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:36:32.677638 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:36:32.681278 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:36:32.684432 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 17:36:32.687190 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 17:36:32.694962 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:36:32.699055 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 12 17:36:32.701376 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 17:36:32.703211 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 17:36:32.708505 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:36:32.714108 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 17:36:32.728210 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 17:36:32.756913 systemd-udevd[1376]: Using default interface naming scheme 'v255'. Sep 12 17:36:32.849418 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 17:36:32.865695 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 17:36:32.870747 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:36:32.876843 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:36:32.995961 augenrules[1431]: No rules Sep 12 17:36:33.002974 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:36:33.003345 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:36:33.080184 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 17:36:33.151314 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 17:36:33.154313 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 17:36:33.169569 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 17:36:33.179863 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 12 17:36:33.188861 kernel: ACPI: button: Power Button [PWRF] Sep 12 17:36:33.230168 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 17:36:33.233201 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). Sep 12 17:36:33.247585 systemd-networkd[1408]: lo: Link UP Sep 12 17:36:33.247603 systemd-networkd[1408]: lo: Gained carrier Sep 12 17:36:33.251372 systemd-networkd[1408]: Enumeration completed Sep 12 17:36:33.251522 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:36:33.254417 systemd-networkd[1408]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:36:33.254433 systemd-networkd[1408]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:36:33.258581 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 12 17:36:33.258910 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 12 17:36:33.259236 systemd-networkd[1408]: eth0: Link UP Sep 12 17:36:33.259400 systemd-networkd[1408]: eth0: Gained carrier Sep 12 17:36:33.259425 systemd-networkd[1408]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:36:33.260849 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 12 17:36:33.264069 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 17:36:33.267108 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 17:36:33.281164 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 12 17:36:33.313656 systemd-networkd[1408]: eth0: DHCPv4 address 10.0.0.3/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 17:36:33.316926 systemd-timesyncd[1372]: Network configuration changed, trying to establish connection. Sep 12 17:36:33.317143 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 17:36:33.321746 systemd-resolved[1371]: Positive Trust Anchors: Sep 12 17:36:33.321770 systemd-resolved[1371]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:36:33.321811 systemd-resolved[1371]: 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 Sep 12 17:36:33.322041 systemd-timesyncd[1372]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 12 17:36:33.322208 systemd-timesyncd[1372]: Initial clock synchronization to Fri 2025-09-12 17:36:33.711308 UTC. Sep 12 17:36:33.332568 systemd-resolved[1371]: Defaulting to hostname 'linux'. Sep 12 17:36:33.334981 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:36:33.337577 systemd[1]: Reached target network.target - Network. Sep 12 17:36:33.339022 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:36:33.341639 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 17:36:33.347453 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:33.360577 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:36:33.360941 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:33.363473 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 17:36:33.373073 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:36:33.500355 ldconfig[1236]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 17:36:33.534559 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 17:36:33.540120 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 17:36:33.585720 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 17:36:33.671896 kernel: kvm_amd: TSC scaling supported Sep 12 17:36:33.672017 kernel: kvm_amd: Nested Virtualization enabled Sep 12 17:36:33.672074 kernel: kvm_amd: Nested Paging enabled Sep 12 17:36:33.673687 kernel: kvm_amd: LBR virtualization supported Sep 12 17:36:33.673729 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 12 17:36:33.674646 kernel: kvm_amd: Virtual GIF supported Sep 12 17:36:33.710866 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:36:33.739596 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:36:33.741412 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 17:36:33.744464 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 12 17:36:33.747554 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 12 17:36:33.750404 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 17:36:33.752223 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 17:36:33.753668 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 17:36:33.755113 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 17:36:33.755191 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:36:33.756385 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:36:33.759404 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 17:36:33.763114 kernel: EDAC MC: Ver: 3.0.0 Sep 12 17:36:33.764032 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:36:33.768859 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 17:36:33.794133 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 17:36:33.796885 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 17:36:33.812349 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 17:36:33.826685 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 17:36:33.829498 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:36:33.831537 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:36:33.832802 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:36:33.834266 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:36:33.834311 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:36:33.836310 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:36:33.886687 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 17:36:33.889764 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 17:36:33.892911 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 17:36:33.897773 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 17:36:33.899149 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). Sep 12 17:36:33.911743 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 12 17:36:33.915534 jq[1489]: false Sep 12 17:36:33.950013 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 17:36:33.953044 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 17:36:33.954331 extend-filesystems[1490]: Found /dev/vda6 Sep 12 17:36:33.956081 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 17:36:33.961743 oslogin_cache_refresh[1491]: Refreshing passwd entry cache Sep 12 17:36:33.994725 google_oslogin_nss_cache[1491]: oslogin_cache_refresh[1491]: Refreshing passwd entry cache Sep 12 17:36:33.997290 extend-filesystems[1490]: Found /dev/vda9 Sep 12 17:36:34.000735 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 17:36:34.001754 google_oslogin_nss_cache[1491]: oslogin_cache_refresh[1491]: Failure getting users, quitting Sep 12 17:36:34.001754 google_oslogin_nss_cache[1491]: oslogin_cache_refresh[1491]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 12 17:36:34.001754 google_oslogin_nss_cache[1491]: oslogin_cache_refresh[1491]: Refreshing group entry cache Sep 12 17:36:34.001650 oslogin_cache_refresh[1491]: Failure getting users, quitting Sep 12 17:36:34.001676 oslogin_cache_refresh[1491]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 12 17:36:34.001744 oslogin_cache_refresh[1491]: Refreshing group entry cache Sep 12 17:36:34.003474 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 17:36:34.005770 extend-filesystems[1490]: Checking size of /dev/vda9 Sep 12 17:36:34.004396 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 17:36:34.008441 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 17:36:34.063279 google_oslogin_nss_cache[1491]: oslogin_cache_refresh[1491]: Failure getting groups, quitting Sep 12 17:36:34.063279 google_oslogin_nss_cache[1491]: oslogin_cache_refresh[1491]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 12 17:36:34.063073 oslogin_cache_refresh[1491]: Failure getting groups, quitting Sep 12 17:36:34.063101 oslogin_cache_refresh[1491]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 12 17:36:34.071247 extend-filesystems[1490]: Old size kept for /dev/vda9 Sep 12 17:36:34.071853 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 17:36:34.078684 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 17:36:34.092219 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 17:36:34.101116 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 17:36:34.101931 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 17:36:34.102313 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 17:36:34.103141 update_engine[1503]: I20250912 17:36:34.102434 1503 main.cc:92] Flatcar Update Engine starting Sep 12 17:36:34.105934 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 12 17:36:34.106357 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 12 17:36:34.108645 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 17:36:34.111017 jq[1514]: true Sep 12 17:36:34.109030 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 17:36:34.116148 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 17:36:34.116945 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 17:36:34.138599 (ntainerd)[1519]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:36:34.157978 jq[1518]: true Sep 12 17:36:34.205196 dbus-daemon[1487]: [system] SELinux support is enabled Sep 12 17:36:34.205934 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 17:36:34.212434 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). Sep 12 17:36:34.212478 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 17:36:34.214753 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). Sep 12 17:36:34.214779 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 17:36:34.219982 update_engine[1503]: I20250912 17:36:34.219913 1503 update_check_scheduler.cc:74] Next update check in 5m40s Sep 12 17:36:34.220140 systemd[1]: Started update-engine.service - Update Engine. Sep 12 17:36:34.225024 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 17:36:34.266948 systemd-logind[1500]: Watching system buttons on /dev/input/event2 (Power Button) Sep 12 17:36:34.266987 systemd-logind[1500]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 17:36:34.270180 systemd-logind[1500]: New seat seat0. Sep 12 17:36:34.271823 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 17:36:34.337626 locksmithd[1531]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 17:36:34.355668 bash[1545]: Updated "/home/core/.ssh/authorized_keys" Sep 12 17:36:34.358705 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 17:36:34.362609 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 17:36:34.631300 sshd_keygen[1510]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 17:36:34.645790 containerd[1519]: time="2025-09-12T17:36:34Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 17:36:34.646890 containerd[1519]: time="2025-09-12T17:36:34.646626017Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 17:36:34.664194 containerd[1519]: time="2025-09-12T17:36:34.663993671Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="17.86µs" Sep 12 17:36:34.664194 containerd[1519]: time="2025-09-12T17:36:34.664068866Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 17:36:34.664194 containerd[1519]: time="2025-09-12T17:36:34.664106542Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 17:36:34.664446 containerd[1519]: time="2025-09-12T17:36:34.664423995Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 17:36:34.664479 containerd[1519]: time="2025-09-12T17:36:34.664449809Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 17:36:34.664509 containerd[1519]: time="2025-09-12T17:36:34.664493274Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:36:34.664730 containerd[1519]: time="2025-09-12T17:36:34.664592823Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:36:34.664730 containerd[1519]: time="2025-09-12T17:36:34.664628535Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:36:34.665166 containerd[1519]: time="2025-09-12T17:36:34.665095294Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:36:34.665166 containerd[1519]: time="2025-09-12T17:36:34.665126592Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:36:34.665166 containerd[1519]: time="2025-09-12T17:36:34.665159909Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:36:34.665532 containerd[1519]: time="2025-09-12T17:36:34.665175983Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 17:36:34.665532 containerd[1519]: time="2025-09-12T17:36:34.665336963Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 17:36:34.666118 containerd[1519]: time="2025-09-12T17:36:34.665752777Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:36:34.666118 containerd[1519]: time="2025-09-12T17:36:34.665830262Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:36:34.666118 containerd[1519]: time="2025-09-12T17:36:34.665849688Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 17:36:34.666118 containerd[1519]: time="2025-09-12T17:36:34.665919724Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 17:36:34.666316 containerd[1519]: time="2025-09-12T17:36:34.666240980Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 17:36:34.666464 containerd[1519]: time="2025-09-12T17:36:34.666355385Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:36:34.678577 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 17:36:34.720602 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 17:36:34.760964 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 17:36:34.761498 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 17:36:34.765557 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 17:36:34.823080 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 17:36:34.831403 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 17:36:34.846497 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 17:36:34.848169 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 17:36:34.965421 containerd[1519]: time="2025-09-12T17:36:34.965161544Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 17:36:34.965421 containerd[1519]: time="2025-09-12T17:36:34.965300156Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 17:36:34.965421 containerd[1519]: time="2025-09-12T17:36:34.965369781Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 17:36:34.965421 containerd[1519]: time="2025-09-12T17:36:34.965414802Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965442706Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965465022Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965493201Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965518595Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965536246Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965551827Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965566767Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 17:36:34.965628 containerd[1519]: time="2025-09-12T17:36:34.965585143Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 17:36:34.966157 containerd[1519]: time="2025-09-12T17:36:34.965968544Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 17:36:34.966157 containerd[1519]: time="2025-09-12T17:36:34.966014993Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 17:36:34.966503 containerd[1519]: time="2025-09-12T17:36:34.966452629Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.966508292Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.966974705Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967038384Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967061687Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967086766Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967110616Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967135884Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967163411Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967331419Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967366595Z" level=info msg="Start snapshots syncer" Sep 12 17:36:34.967504 containerd[1519]: time="2025-09-12T17:36:34.967410837Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 17:36:34.968545 containerd[1519]: time="2025-09-12T17:36:34.968458268Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 17:36:34.968696 containerd[1519]: time="2025-09-12T17:36:34.968575405Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 17:36:34.968738 containerd[1519]: time="2025-09-12T17:36:34.968711505Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 17:36:34.969072 containerd[1519]: time="2025-09-12T17:36:34.969023126Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 17:36:34.969072 containerd[1519]: time="2025-09-12T17:36:34.969057860Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 17:36:34.969072 containerd[1519]: time="2025-09-12T17:36:34.969069659Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969081101Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969111223Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969123420Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969138834Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969166371Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969178516Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 17:36:34.969211 containerd[1519]: time="2025-09-12T17:36:34.969190819Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969237027Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969260971Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969270784Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969280933Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969290515Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969300160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969311202Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969334148Z" level=info msg="runtime interface created" Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969343972Z" level=info msg="created NRI interface" Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969355130Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 17:36:34.969385 containerd[1519]: time="2025-09-12T17:36:34.969376563Z" level=info msg="Connect containerd service" Sep 12 17:36:34.969650 containerd[1519]: time="2025-09-12T17:36:34.969410267Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:36:34.970431 containerd[1519]: time="2025-09-12T17:36:34.970403579Z" 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" Sep 12 17:36:35.197171 systemd-networkd[1408]: eth0: Gained IPv6LL Sep 12 17:36:35.201397 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 17:36:35.211344 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 17:36:35.215385 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 12 17:36:35.218980 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 17:36:35.252174 containerd[1519]: time="2025-09-12T17:36:35.252098961Z" level=info msg="Start subscribing containerd event" Sep 12 17:36:35.252650 containerd[1519]: time="2025-09-12T17:36:35.252428496Z" level=info msg="Start recovering state" Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253015271Z" level=info msg="Start event monitor" Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253072021Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253084931Z" level=info msg="Start streaming server" Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253097685Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253110910Z" level=info msg="runtime interface starting up..." Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253143301Z" level=info msg="starting plugins..." Sep 12 17:36:35.253216 containerd[1519]: time="2025-09-12T17:36:35.253181709Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 17:36:35.253421 containerd[1519]: time="2025-09-12T17:36:35.253376977Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:36:35.253741 containerd[1519]: time="2025-09-12T17:36:35.253481620Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:36:35.253833 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:36:35.254416 containerd[1519]: time="2025-09-12T17:36:35.254339665Z" level=info msg="containerd successfully booted in 0.609330s" Sep 12 17:36:35.308493 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 17:36:35.312723 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 12 17:36:35.313288 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 12 17:36:35.316564 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 17:36:35.316980 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 17:36:35.318837 systemd[1]: Startup finished in 4.546s (kernel) + 6.710s (initrd) + 7.011s (userspace) = 18.267s. Sep 12 17:36:36.059189 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 17:36:36.060698 systemd[1]: Started sshd@0-10.0.0.3:22-10.0.0.1:43166.service - OpenSSH per-connection server daemon (10.0.0.1:43166). Sep 12 17:36:36.263356 sshd[1610]: Accepted publickey for core from 10.0.0.1 port 43166 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:36.267290 sshd-session[1610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:36.275725 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 17:36:36.276994 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 17:36:36.284759 systemd-logind[1500]: New session 1 of user core. Sep 12 17:36:36.305620 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 17:36:36.309180 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 17:36:36.360583 (systemd)[1615]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 17:36:36.365699 systemd-logind[1500]: New session c1 of user core. Sep 12 17:36:36.562145 systemd[1615]: Queued start job for default target default.target. Sep 12 17:36:36.584057 systemd[1615]: Created slice app.slice - User Application Slice. Sep 12 17:36:36.584097 systemd[1615]: Reached target paths.target - Paths. Sep 12 17:36:36.584156 systemd[1615]: Reached target timers.target - Timers. Sep 12 17:36:36.586403 systemd[1615]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:36:36.604763 systemd[1615]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:36:36.605027 systemd[1615]: Reached target sockets.target - Sockets. Sep 12 17:36:36.605110 systemd[1615]: Reached target basic.target - Basic System. Sep 12 17:36:36.605168 systemd[1615]: Reached target default.target - Main User Target. Sep 12 17:36:36.605242 systemd[1615]: Startup finished in 227ms. Sep 12 17:36:36.605862 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 17:36:36.608206 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 17:36:36.677716 systemd[1]: Started sshd@1-10.0.0.3:22-10.0.0.1:43176.service - OpenSSH per-connection server daemon (10.0.0.1:43176). Sep 12 17:36:36.756095 sshd[1626]: Accepted publickey for core from 10.0.0.1 port 43176 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:36.758314 sshd-session[1626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:36.765193 systemd-logind[1500]: New session 2 of user core. Sep 12 17:36:36.779246 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 17:36:36.873877 sshd[1629]: Connection closed by 10.0.0.1 port 43176 Sep 12 17:36:36.874776 sshd-session[1626]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:36.886839 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:43176.service: Deactivated successfully. Sep 12 17:36:36.889734 systemd[1]: session-2.scope: Deactivated successfully. Sep 12 17:36:36.890941 systemd-logind[1500]: Session 2 logged out. Waiting for processes to exit. Sep 12 17:36:36.895157 systemd[1]: Started sshd@2-10.0.0.3:22-10.0.0.1:43178.service - OpenSSH per-connection server daemon (10.0.0.1:43178). Sep 12 17:36:36.895940 systemd-logind[1500]: Removed session 2. Sep 12 17:36:36.959486 sshd[1635]: Accepted publickey for core from 10.0.0.1 port 43178 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:36.961977 sshd-session[1635]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:36.971275 systemd-logind[1500]: New session 3 of user core. Sep 12 17:36:36.994162 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 17:36:37.048795 sshd[1638]: Connection closed by 10.0.0.1 port 43178 Sep 12 17:36:37.049326 sshd-session[1635]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:37.059080 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:43178.service: Deactivated successfully. Sep 12 17:36:37.061493 systemd[1]: session-3.scope: Deactivated successfully. Sep 12 17:36:37.062728 systemd-logind[1500]: Session 3 logged out. Waiting for processes to exit. Sep 12 17:36:37.066063 systemd[1]: Started sshd@3-10.0.0.3:22-10.0.0.1:43194.service - OpenSSH per-connection server daemon (10.0.0.1:43194). Sep 12 17:36:37.067488 systemd-logind[1500]: Removed session 3. Sep 12 17:36:37.138615 sshd[1644]: Accepted publickey for core from 10.0.0.1 port 43194 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:37.140505 sshd-session[1644]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:37.146336 systemd-logind[1500]: New session 4 of user core. Sep 12 17:36:37.157020 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 17:36:37.214252 sshd[1647]: Connection closed by 10.0.0.1 port 43194 Sep 12 17:36:37.215034 sshd-session[1644]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:37.230529 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:43194.service: Deactivated successfully. Sep 12 17:36:37.232484 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 17:36:37.233405 systemd-logind[1500]: Session 4 logged out. Waiting for processes to exit. Sep 12 17:36:37.236376 systemd[1]: Started sshd@4-10.0.0.3:22-10.0.0.1:43196.service - OpenSSH per-connection server daemon (10.0.0.1:43196). Sep 12 17:36:37.237056 systemd-logind[1500]: Removed session 4. Sep 12 17:36:37.306289 sshd[1653]: Accepted publickey for core from 10.0.0.1 port 43196 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:37.308111 sshd-session[1653]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:37.313943 systemd-logind[1500]: New session 5 of user core. Sep 12 17:36:37.324193 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 17:36:37.396935 sudo[1657]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 12 17:36:37.397420 sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:36:37.424207 sudo[1657]: pam_unix(sudo:session): session closed for user root Sep 12 17:36:37.427249 sshd[1656]: Connection closed by 10.0.0.1 port 43196 Sep 12 17:36:37.427813 sshd-session[1653]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:37.448366 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:43196.service: Deactivated successfully. Sep 12 17:36:37.451008 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 17:36:37.452232 systemd-logind[1500]: Session 5 logged out. Waiting for processes to exit. Sep 12 17:36:37.457011 systemd[1]: Started sshd@5-10.0.0.3:22-10.0.0.1:43202.service - OpenSSH per-connection server daemon (10.0.0.1:43202). Sep 12 17:36:37.458223 systemd-logind[1500]: Removed session 5. Sep 12 17:36:37.521486 sshd[1663]: Accepted publickey for core from 10.0.0.1 port 43202 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:37.523553 sshd-session[1663]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:37.529634 systemd-logind[1500]: New session 6 of user core. Sep 12 17:36:37.539104 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 17:36:37.598206 sudo[1668]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 12 17:36:37.598592 sudo[1668]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:36:37.737233 sudo[1668]: pam_unix(sudo:session): session closed for user root Sep 12 17:36:37.745246 sudo[1667]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 12 17:36:37.745597 sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:36:37.758878 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:36:37.839845 augenrules[1690]: No rules Sep 12 17:36:37.841077 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:36:37.841369 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:36:37.842962 sudo[1667]: pam_unix(sudo:session): session closed for user root Sep 12 17:36:37.844840 sshd[1666]: Connection closed by 10.0.0.1 port 43202 Sep 12 17:36:37.845425 sshd-session[1663]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:37.855309 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:43202.service: Deactivated successfully. Sep 12 17:36:37.857737 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 17:36:37.858746 systemd-logind[1500]: Session 6 logged out. Waiting for processes to exit. Sep 12 17:36:37.862567 systemd[1]: Started sshd@6-10.0.0.3:22-10.0.0.1:43208.service - OpenSSH per-connection server daemon (10.0.0.1:43208). Sep 12 17:36:37.863457 systemd-logind[1500]: Removed session 6. Sep 12 17:36:37.929100 sshd[1699]: Accepted publickey for core from 10.0.0.1 port 43208 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:37.931394 sshd-session[1699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:37.937626 systemd-logind[1500]: New session 7 of user core. Sep 12 17:36:37.951276 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 17:36:38.011800 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 17:36:38.036369 (dockerd)[1704]: 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 Sep 12 17:36:38.496074 dockerd[1704]: time="2025-09-12T17:36:38.495973559Z" level=info msg="Starting up" Sep 12 17:36:38.497081 dockerd[1704]: time="2025-09-12T17:36:38.497004384Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 12 17:36:38.526760 dockerd[1704]: time="2025-09-12T17:36:38.526677289Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 12 17:36:38.676486 dockerd[1704]: time="2025-09-12T17:36:38.676403495Z" level=info msg="Loading containers: start." Sep 12 17:36:38.688910 kernel: Initializing XFRM netlink socket Sep 12 17:36:39.108481 systemd-networkd[1408]: docker0: Link UP Sep 12 17:36:39.114523 dockerd[1704]: time="2025-09-12T17:36:39.114469221Z" level=info msg="Loading containers: done." Sep 12 17:36:39.131601 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck197799757-merged.mount: Deactivated successfully. Sep 12 17:36:39.133133 dockerd[1704]: time="2025-09-12T17:36:39.133072300Z" 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 Sep 12 17:36:39.133254 dockerd[1704]: time="2025-09-12T17:36:39.133184602Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 12 17:36:39.133289 dockerd[1704]: time="2025-09-12T17:36:39.133278460Z" level=info msg="Initializing buildkit" Sep 12 17:36:39.164658 dockerd[1704]: time="2025-09-12T17:36:39.164600358Z" level=info msg="Completed buildkit initialization" Sep 12 17:36:39.171031 dockerd[1704]: time="2025-09-12T17:36:39.170991432Z" level=info msg="Daemon has completed initialization" Sep 12 17:36:39.171128 dockerd[1704]: time="2025-09-12T17:36:39.171070984Z" level=info msg="API listen on /run/docker.sock" Sep 12 17:36:39.171270 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 17:36:39.184695 sshd[1702]: Connection closed by 10.0.0.1 port 43208 Sep 12 17:36:39.185106 sshd-session[1699]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:39.200008 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:43208.service: Deactivated successfully. Sep 12 17:36:39.202163 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 17:36:39.203073 systemd-logind[1500]: Session 7 logged out. Waiting for processes to exit. Sep 12 17:36:39.206078 systemd[1]: Started sshd@7-10.0.0.3:22-10.0.0.1:43222.service - OpenSSH per-connection server daemon (10.0.0.1:43222). Sep 12 17:36:39.206586 systemd-logind[1500]: Removed session 7. Sep 12 17:36:39.278650 sshd[1918]: Accepted publickey for core from 10.0.0.1 port 43222 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:39.280652 sshd-session[1918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:39.286125 systemd-logind[1500]: New session 8 of user core. Sep 12 17:36:39.300053 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 17:36:41.987997 systemd[1]: var-lib-docker-overlay2-73bc498c5381901854de4b86af3be475243880a905411ba24e1eb130b762c048\x2dinit-merged.mount: Deactivated successfully. Sep 12 17:36:42.105946 containerd[1519]: time="2025-09-12T17:36:42.105592428Z" level=info msg="connecting to shim 01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655" address="unix:///run/containerd/s/01c499b1b094ed3560c6b3fb55532214eac32d5e6258d0425e8acf149bd1bc46" namespace=moby protocol=ttrpc version=3 Sep 12 17:36:42.167056 systemd[1]: Started docker-01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655.scope - libcontainer container 01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655. Sep 12 17:36:42.194137 systemd-resolved[1371]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:36:42.262079 kernel: docker0: port 1(veth3d2421c) entered blocking state Sep 12 17:36:42.262269 kernel: docker0: port 1(veth3d2421c) entered disabled state Sep 12 17:36:42.262338 kernel: veth3d2421c: entered allmulticast mode Sep 12 17:36:42.265109 kernel: veth3d2421c: entered promiscuous mode Sep 12 17:36:42.264802 systemd-networkd[1408]: veth3d2421c: Link UP Sep 12 17:36:42.283912 kernel: eth0: renamed from veth73a1c31 Sep 12 17:36:42.286274 kernel: docker0: port 1(veth3d2421c) entered blocking state Sep 12 17:36:42.286373 kernel: docker0: port 1(veth3d2421c) entered forwarding state Sep 12 17:36:42.286562 systemd-networkd[1408]: veth3d2421c: Gained carrier Sep 12 17:36:42.286934 systemd-networkd[1408]: docker0: Gained carrier Sep 12 17:36:42.336204 sshd[1921]: Connection closed by 10.0.0.1 port 43222 Sep 12 17:36:42.335848 sshd-session[1918]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:42.354314 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:43222.service: Deactivated successfully. Sep 12 17:36:42.357242 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 17:36:42.359348 systemd-logind[1500]: Session 8 logged out. Waiting for processes to exit. Sep 12 17:36:42.364139 systemd[1]: Started sshd@8-10.0.0.3:22-10.0.0.1:51298.service - OpenSSH per-connection server daemon (10.0.0.1:51298). Sep 12 17:36:42.365081 systemd-logind[1500]: Removed session 8. Sep 12 17:36:42.441469 sshd[1998]: Accepted publickey for core from 10.0.0.1 port 51298 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:42.444193 sshd-session[1998]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:42.451460 systemd-logind[1500]: New session 9 of user core. Sep 12 17:36:42.459044 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 17:36:42.524705 sshd[2001]: Connection closed by 10.0.0.1 port 51298 Sep 12 17:36:42.525055 sshd-session[1998]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:42.533717 systemd[1]: sshd@8-10.0.0.3:22-10.0.0.1:51298.service: Deactivated successfully. Sep 12 17:36:42.536088 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 17:36:42.536857 systemd-logind[1500]: Session 9 logged out. Waiting for processes to exit. Sep 12 17:36:42.539930 systemd[1]: Started sshd@9-10.0.0.3:22-10.0.0.1:51308.service - OpenSSH per-connection server daemon (10.0.0.1:51308). Sep 12 17:36:42.540805 systemd-logind[1500]: Removed session 9. Sep 12 17:36:42.616030 sshd[2007]: Accepted publickey for core from 10.0.0.1 port 51308 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:42.618055 sshd-session[2007]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:42.623979 systemd-logind[1500]: New session 10 of user core. Sep 12 17:36:42.634022 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 17:36:42.692298 sudo[2011]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1519 Sep 12 17:36:42.692699 sudo[2011]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:36:42.697469 sudo[2011]: pam_unix(sudo:session): session closed for user root Sep 12 17:36:42.698388 containerd[1519]: time="2025-09-12T17:36:42.697711641Z" level=info msg="Stop CRI service" Sep 12 17:36:42.698455 dockerd[1704]: time="2025-09-12T17:36:42.698028121Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 12 17:36:42.698455 dockerd[1704]: time="2025-09-12T17:36:42.698080876Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 12 17:36:42.698455 dockerd[1704]: time="2025-09-12T17:36:42.698060595Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 12 17:36:42.698455 dockerd[1704]: time="2025-09-12T17:36:42.698183330Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 12 17:36:42.698959 containerd[1519]: time="2025-09-12T17:36:42.698581902Z" level=info msg="Stop CRI service" Sep 12 17:36:42.699343 sshd[2010]: Connection closed by 10.0.0.1 port 51308 Sep 12 17:36:42.699717 sshd-session[2007]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:42.708810 systemd[1]: containerd.service: Deactivated successfully. Sep 12 17:36:42.709019 systemd[1]: containerd.service: Unit process 1946 (containerd-shim) remains running after unit stopped. Sep 12 17:36:42.709408 systemd[1]: containerd.service: Consumed 823ms CPU time, 125.5M memory peak. Sep 12 17:36:42.710105 systemd[1]: sshd@9-10.0.0.3:22-10.0.0.1:51308.service: Deactivated successfully. Sep 12 17:36:42.712159 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 17:36:42.713658 systemd-logind[1500]: Session 10 logged out. Waiting for processes to exit. Sep 12 17:36:42.717240 systemd[1]: Started sshd@10-10.0.0.3:22-10.0.0.1:51320.service - OpenSSH per-connection server daemon (10.0.0.1:51320). Sep 12 17:36:42.718487 systemd-logind[1500]: Removed session 10. Sep 12 17:36:42.777154 sshd[2018]: Accepted publickey for core from 10.0.0.1 port 51320 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:42.779353 sshd-session[2018]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:42.785420 systemd-logind[1500]: New session 11 of user core. Sep 12 17:36:42.797087 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 17:36:42.857388 sshd[2021]: Connection closed by 10.0.0.1 port 51320 Sep 12 17:36:42.857703 sshd-session[2018]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:42.861988 systemd[1]: sshd@10-10.0.0.3:22-10.0.0.1:51320.service: Deactivated successfully. Sep 12 17:36:42.863988 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 17:36:42.864818 systemd-logind[1500]: Session 11 logged out. Waiting for processes to exit. Sep 12 17:36:42.866319 systemd-logind[1500]: Removed session 11. Sep 12 17:36:44.093126 systemd-networkd[1408]: docker0: Gained IPv6LL Sep 12 17:36:44.157055 systemd-networkd[1408]: veth3d2421c: Gained IPv6LL Sep 12 17:36:47.959587 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Sep 12 17:36:47.960083 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 12 17:36:47.960267 dockerd[1704]: time="2025-09-12T17:36:47.960206018Z" level=info msg="Processing signal 'terminated'" Sep 12 17:36:48.950283 systemd[1]: Started sshd@11-10.0.0.3:22-10.0.0.1:51326.service - OpenSSH per-connection server daemon (10.0.0.1:51326). Sep 12 17:36:49.010716 sshd[2027]: Accepted publickey for core from 10.0.0.1 port 51326 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:49.013001 sshd-session[2027]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:49.018669 systemd-logind[1500]: New session 12 of user core. Sep 12 17:36:49.030098 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 17:36:49.091253 sshd[2030]: Connection closed by 10.0.0.1 port 51326 Sep 12 17:36:49.091599 sshd-session[2027]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:49.096353 systemd[1]: sshd@11-10.0.0.3:22-10.0.0.1:51326.service: Deactivated successfully. Sep 12 17:36:49.098657 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 17:36:49.099708 systemd-logind[1500]: Session 12 logged out. Waiting for processes to exit. Sep 12 17:36:49.101459 systemd-logind[1500]: Removed session 12. Sep 12 17:36:55.137901 systemd[1]: Started sshd@12-10.0.0.3:22-10.0.0.1:58210.service - OpenSSH per-connection server daemon (10.0.0.1:58210). Sep 12 17:36:55.205133 sshd[2036]: Accepted publickey for core from 10.0.0.1 port 58210 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:36:55.207279 sshd-session[2036]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:36:55.212543 systemd-logind[1500]: New session 13 of user core. Sep 12 17:36:55.225005 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 17:36:55.284710 sshd[2039]: Connection closed by 10.0.0.1 port 58210 Sep 12 17:36:55.285060 sshd-session[2036]: pam_unix(sshd:session): session closed for user core Sep 12 17:36:55.289901 systemd[1]: sshd@12-10.0.0.3:22-10.0.0.1:58210.service: Deactivated successfully. Sep 12 17:36:55.292048 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 17:36:55.292975 systemd-logind[1500]: Session 13 logged out. Waiting for processes to exit. Sep 12 17:36:55.294236 systemd-logind[1500]: Removed session 13. Sep 12 17:37:01.315629 systemd[1]: Started sshd@13-10.0.0.3:22-10.0.0.1:33786.service - OpenSSH per-connection server daemon (10.0.0.1:33786). Sep 12 17:37:01.383392 sshd[2045]: Accepted publickey for core from 10.0.0.1 port 33786 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:01.385792 sshd-session[2045]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:01.391312 systemd-logind[1500]: New session 14 of user core. Sep 12 17:37:01.401152 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 17:37:01.465673 sshd[2048]: Connection closed by 10.0.0.1 port 33786 Sep 12 17:37:01.466100 sshd-session[2045]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:01.471492 systemd[1]: sshd@13-10.0.0.3:22-10.0.0.1:33786.service: Deactivated successfully. Sep 12 17:37:01.473716 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 17:37:01.474977 systemd-logind[1500]: Session 14 logged out. Waiting for processes to exit. Sep 12 17:37:01.477211 systemd-logind[1500]: Removed session 14. Sep 12 17:37:02.961038 dockerd[1704]: time="2025-09-12T17:37:02.960933012Z" level=error msg="Force shutdown daemon" Sep 12 17:37:02.961686 dockerd[1704]: time="2025-09-12T17:37:02.961469929Z" level=warning msg="Error while testing if containerd API is ready" error="Canceled: received context error while waiting for new LB policy update: context canceled" Sep 12 17:37:02.961686 dockerd[1704]: time="2025-09-12T17:37:02.961514263Z" level=info msg="stopping event stream following graceful shutdown" error="context canceled" module=libcontainerd namespace=moby Sep 12 17:37:02.961686 dockerd[1704]: time="2025-09-12T17:37:02.961229671Z" level=info msg="Daemon shutdown complete" Sep 12 17:37:02.961686 dockerd[1704]: time="2025-09-12T17:37:02.961556270Z" level=warning msg="Error while testing if containerd API is ready" error="Canceled: received context error while waiting for new LB policy update: context canceled" Sep 12 17:37:02.967896 systemd[1]: docker.service: Deactivated successfully. Sep 12 17:37:02.968559 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 12 17:37:02.968666 systemd[1]: docker.service: Consumed 1.870s CPU time, 128.6M memory peak. Sep 12 17:37:02.970239 systemd[1]: docker.socket: Deactivated successfully. Sep 12 17:37:02.970797 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 12 17:37:02.970861 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 12 17:37:02.973038 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:37:02.973322 systemd[1]: containerd.service: Found left-over process 1946 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 17:37:02.973331 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 17:37:02.982535 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:37:02.984002 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:37:03.014279 systemd[1]: containerd.service: Found left-over process 1946 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 17:37:03.014294 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 17:37:03.027235 (ntainerd)[2059]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:37:03.050736 containerd[2059]: time="2025-09-12T17:37:03Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 17:37:03.051554 containerd[2059]: time="2025-09-12T17:37:03.051500721Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 17:37:03.061988 containerd[2059]: time="2025-09-12T17:37:03.061896893Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="19.165µs" Sep 12 17:37:03.061988 containerd[2059]: time="2025-09-12T17:37:03.061957950Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 17:37:03.061988 containerd[2059]: time="2025-09-12T17:37:03.061984036Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 17:37:03.062263 containerd[2059]: time="2025-09-12T17:37:03.062044350Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 17:37:03.062263 containerd[2059]: time="2025-09-12T17:37:03.062062732Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 17:37:03.062456 containerd[2059]: time="2025-09-12T17:37:03.062410606Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:37:03.062505 containerd[2059]: time="2025-09-12T17:37:03.062460580Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:37:03.062505 containerd[2059]: time="2025-09-12T17:37:03.062475944Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:37:03.062957 containerd[2059]: time="2025-09-12T17:37:03.062915572Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:37:03.062957 containerd[2059]: time="2025-09-12T17:37:03.062941628Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:37:03.063013 containerd[2059]: time="2025-09-12T17:37:03.062958065Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:37:03.063013 containerd[2059]: time="2025-09-12T17:37:03.062970922Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 17:37:03.063013 containerd[2059]: time="2025-09-12T17:37:03.063008721Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 17:37:03.063243 containerd[2059]: time="2025-09-12T17:37:03.063162094Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:37:03.063243 containerd[2059]: time="2025-09-12T17:37:03.063208066Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:37:03.063243 containerd[2059]: time="2025-09-12T17:37:03.063236077Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 17:37:03.063472 containerd[2059]: time="2025-09-12T17:37:03.063284116Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 17:37:03.064697 containerd[2059]: time="2025-09-12T17:37:03.064638142Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 17:37:03.064924 containerd[2059]: time="2025-09-12T17:37:03.064882537Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:37:03.065139 containerd[2059]: time="2025-09-12T17:37:03.065093114Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 17:37:03.065139 containerd[2059]: time="2025-09-12T17:37:03.065138425Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 17:37:03.065236 containerd[2059]: time="2025-09-12T17:37:03.065153650Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 17:37:03.065236 containerd[2059]: time="2025-09-12T17:37:03.065167389Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 17:37:03.065236 containerd[2059]: time="2025-09-12T17:37:03.065180888Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 17:37:03.065236 containerd[2059]: time="2025-09-12T17:37:03.065192772Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 17:37:03.065236 containerd[2059]: time="2025-09-12T17:37:03.065207364Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 17:37:03.065635 containerd[2059]: time="2025-09-12T17:37:03.065239578Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 17:37:03.065635 containerd[2059]: time="2025-09-12T17:37:03.065253558Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 17:37:03.065635 containerd[2059]: time="2025-09-12T17:37:03.065266495Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 17:37:03.065635 containerd[2059]: time="2025-09-12T17:37:03.065278119Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 17:37:03.065635 containerd[2059]: time="2025-09-12T17:37:03.065293603Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 17:37:03.065635 containerd[2059]: time="2025-09-12T17:37:03.065606416Z" level=info msg="connecting to shim 01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655" address="unix:///run/containerd/s/01c499b1b094ed3560c6b3fb55532214eac32d5e6258d0425e8acf149bd1bc46" namespace=moby protocol=ttrpc version=3 Sep 12 17:37:03.076625 containerd[2059]: time="2025-09-12T17:37:03.076545023Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 17:37:03.076625 containerd[2059]: time="2025-09-12T17:37:03.076615967Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 17:37:03.076625 containerd[2059]: time="2025-09-12T17:37:03.076643256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076663173Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076680052Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076700691Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076723999Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076753504Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076770443Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076785937Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 17:37:03.076882 containerd[2059]: time="2025-09-12T17:37:03.076858477Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 17:37:03.077086 containerd[2059]: time="2025-09-12T17:37:03.076960421Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 17:37:03.077086 containerd[2059]: time="2025-09-12T17:37:03.076980729Z" level=info msg="Start snapshots syncer" Sep 12 17:37:03.077086 containerd[2059]: time="2025-09-12T17:37:03.077026190Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 17:37:03.077407 containerd[2059]: time="2025-09-12T17:37:03.077351680Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 17:37:03.077540 containerd[2059]: time="2025-09-12T17:37:03.077428110Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 17:37:03.077540 containerd[2059]: time="2025-09-12T17:37:03.077518882Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 17:37:03.077587 containerd[2059]: time="2025-09-12T17:37:03.077572466Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 17:37:03.077611 containerd[2059]: time="2025-09-12T17:37:03.077595142Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 17:37:03.077635 containerd[2059]: time="2025-09-12T17:37:03.077617296Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 17:37:03.077635 containerd[2059]: time="2025-09-12T17:37:03.077630674Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 17:37:03.077678 containerd[2059]: time="2025-09-12T17:37:03.077645647Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 17:37:03.077678 containerd[2059]: time="2025-09-12T17:37:03.077658535Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 17:37:03.077678 containerd[2059]: time="2025-09-12T17:37:03.077672766Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 17:37:03.077738 containerd[2059]: time="2025-09-12T17:37:03.077703986Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 17:37:03.077738 containerd[2059]: time="2025-09-12T17:37:03.077717986Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 17:37:03.077738 containerd[2059]: time="2025-09-12T17:37:03.077730472Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 17:37:03.077801 containerd[2059]: time="2025-09-12T17:37:03.077773457Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:37:03.077801 containerd[2059]: time="2025-09-12T17:37:03.077789854Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:37:03.077879 containerd[2059]: time="2025-09-12T17:37:03.077800855Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:37:03.077879 containerd[2059]: time="2025-09-12T17:37:03.077828897Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:37:03.077879 containerd[2059]: time="2025-09-12T17:37:03.077841282Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 17:37:03.077879 containerd[2059]: time="2025-09-12T17:37:03.077852876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 17:37:03.077879 containerd[2059]: time="2025-09-12T17:37:03.077867156Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 17:37:03.077977 containerd[2059]: time="2025-09-12T17:37:03.077894816Z" level=info msg="runtime interface created" Sep 12 17:37:03.077977 containerd[2059]: time="2025-09-12T17:37:03.077907293Z" level=info msg="created NRI interface" Sep 12 17:37:03.077977 containerd[2059]: time="2025-09-12T17:37:03.077950336Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 17:37:03.077977 containerd[2059]: time="2025-09-12T17:37:03.077965751Z" level=info msg="Connect containerd service" Sep 12 17:37:03.078085 containerd[2059]: time="2025-09-12T17:37:03.077997753Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:37:03.078771 containerd[2059]: time="2025-09-12T17:37:03.078736003Z" 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" Sep 12 17:37:03.095769 containerd[2059]: time="2025-09-12T17:37:03.095695349Z" level=info msg="Start subscribing containerd event" Sep 12 17:37:03.095957 containerd[2059]: time="2025-09-12T17:37:03.095784476Z" level=info msg="Start recovering state" Sep 12 17:37:03.096060 containerd[2059]: time="2025-09-12T17:37:03.095958850Z" level=info msg="Start event monitor" Sep 12 17:37:03.096060 containerd[2059]: time="2025-09-12T17:37:03.095981374Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:37:03.096060 containerd[2059]: time="2025-09-12T17:37:03.095994352Z" level=info msg="Start streaming server" Sep 12 17:37:03.096060 containerd[2059]: time="2025-09-12T17:37:03.096007280Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 17:37:03.096060 containerd[2059]: time="2025-09-12T17:37:03.096016837Z" level=info msg="runtime interface starting up..." Sep 12 17:37:03.096060 containerd[2059]: time="2025-09-12T17:37:03.096016005Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:37:03.096361 containerd[2059]: time="2025-09-12T17:37:03.096027157Z" level=info msg="starting plugins..." Sep 12 17:37:03.096361 containerd[2059]: time="2025-09-12T17:37:03.096120145Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 17:37:03.096361 containerd[2059]: time="2025-09-12T17:37:03.096141406Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:37:03.096448 containerd[2059]: time="2025-09-12T17:37:03.096433850Z" level=info msg="containerd successfully booted in 0.045997s" Sep 12 17:37:03.096624 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:37:03.099091 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 17:37:03.124445 (dockerd)[2087]: 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 Sep 12 17:37:03.171932 dockerd[2087]: time="2025-09-12T17:37:03.171801791Z" level=info msg="Starting up" Sep 12 17:37:03.174142 dockerd[2087]: time="2025-09-12T17:37:03.174088608Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 12 17:37:03.191472 dockerd[2087]: time="2025-09-12T17:37:03.191388997Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 12 17:37:03.227669 dockerd[2087]: time="2025-09-12T17:37:03.227467702Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 12 17:37:03.257847 dockerd[2087]: time="2025-09-12T17:37:03.257744749Z" level=info msg="Loading containers: start." Sep 12 17:37:07.486361 systemd[1]: Started sshd@14-10.0.0.3:22-10.0.0.1:33800.service - OpenSSH per-connection server daemon (10.0.0.1:33800). Sep 12 17:37:07.556283 sshd[2120]: Accepted publickey for core from 10.0.0.1 port 33800 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:07.558143 sshd-session[2120]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:07.563107 systemd-logind[1500]: New session 15 of user core. Sep 12 17:37:07.573134 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 17:37:07.633776 sshd[2123]: Connection closed by 10.0.0.1 port 33800 Sep 12 17:37:07.634310 sshd-session[2120]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:07.647913 systemd[1]: sshd@14-10.0.0.3:22-10.0.0.1:33800.service: Deactivated successfully. Sep 12 17:37:07.650191 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 17:37:07.650998 systemd-logind[1500]: Session 15 logged out. Waiting for processes to exit. Sep 12 17:37:07.653955 systemd[1]: Started sshd@15-10.0.0.3:22-10.0.0.1:33804.service - OpenSSH per-connection server daemon (10.0.0.1:33804). Sep 12 17:37:07.654846 systemd-logind[1500]: Removed session 15. Sep 12 17:37:07.713525 sshd[2129]: Accepted publickey for core from 10.0.0.1 port 33804 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:07.715468 sshd-session[2129]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:07.720907 systemd-logind[1500]: New session 16 of user core. Sep 12 17:37:07.735192 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 17:37:07.795074 sshd[2132]: Connection closed by 10.0.0.1 port 33804 Sep 12 17:37:07.795190 sshd-session[2129]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:07.809702 systemd[1]: sshd@15-10.0.0.3:22-10.0.0.1:33804.service: Deactivated successfully. Sep 12 17:37:07.811917 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 17:37:07.812722 systemd-logind[1500]: Session 16 logged out. Waiting for processes to exit. Sep 12 17:37:07.815572 systemd[1]: Started sshd@16-10.0.0.3:22-10.0.0.1:33812.service - OpenSSH per-connection server daemon (10.0.0.1:33812). Sep 12 17:37:07.816421 systemd-logind[1500]: Removed session 16. Sep 12 17:37:07.885315 sshd[2138]: Accepted publickey for core from 10.0.0.1 port 33812 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:07.887237 sshd-session[2138]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:07.893712 systemd-logind[1500]: New session 17 of user core. Sep 12 17:37:07.903371 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 17:37:13.298797 dockerd[2087]: time="2025-09-12T17:37:13.298618754Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655 Sep 12 17:37:13.317809 systemd[1]: docker-01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655.scope: Deactivated successfully. Sep 12 17:37:13.336040 containerd[2059]: time="2025-09-12T17:37:13.335979384Z" level=info msg="shim disconnected" id=01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655 namespace=moby Sep 12 17:37:13.336541 dockerd[2087]: time="2025-09-12T17:37:13.336211447Z" level=info msg="ignoring event" container=01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 12 17:37:13.336917 containerd[2059]: time="2025-09-12T17:37:13.336856142Z" level=warning msg="cleaning up after shim disconnected" id=01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655 namespace=moby Sep 12 17:37:13.336917 containerd[2059]: time="2025-09-12T17:37:13.336896969Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 17:37:13.518403 systemd[1]: var-lib-docker-overlay2-73bc498c5381901854de4b86af3be475243880a905411ba24e1eb130b762c048-merged.mount: Deactivated successfully. Sep 12 17:37:13.988774 systemd-networkd[1408]: veth3d2421c: Link DOWN Sep 12 17:37:13.988790 systemd-networkd[1408]: veth3d2421c: Lost carrier Sep 12 17:37:13.989769 systemd[1]: run-docker-netns-1eb699929be3.mount: Deactivated successfully. Sep 12 17:37:13.990860 kernel: docker0: port 1(veth3d2421c) entered disabled state Sep 12 17:37:13.993163 kernel: veth3d2421c (unregistering): left allmulticast mode Sep 12 17:37:13.993206 kernel: veth3d2421c (unregistering): left promiscuous mode Sep 12 17:37:13.993227 kernel: docker0: port 1(veth3d2421c) entered disabled state Sep 12 17:37:13.994963 systemd-networkd[1408]: docker0: Lost carrier Sep 12 17:37:13.997639 dockerd[2087]: time="2025-09-12T17:37:13.996968422Z" level=info msg="Removing stale sandbox 1eb699929be323fc8de8b74c03b294ade518e4679edb284156f7517e7330115a (01b507370f3fb0eb64195cc83628f8e8523812ddac7a862b93931448b3bea655)" Sep 12 17:37:14.032628 dockerd[2087]: time="2025-09-12T17:37:14.032583232Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 12 17:37:14.032693 dockerd[2087]: time="2025-09-12T17:37:14.032632570Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint 4435cc4b6b94edcd3b2211b93039ada0afdb7643cb07543a1db5adef16c46b3a 9f5928147cca98b9dca85aa47e3ae8c86eca1e00ebd9e56ee450e4e4e5932f45], retrying...." Sep 12 17:37:14.086720 systemd[1]: run-docker-netns-1eb699929be3.mount: Deactivated successfully. Sep 12 17:37:14.371652 dockerd[2087]: time="2025-09-12T17:37:14.371585097Z" level=warning msg="error locating sandbox id 1eb699929be323fc8de8b74c03b294ade518e4679edb284156f7517e7330115a: sandbox 1eb699929be323fc8de8b74c03b294ade518e4679edb284156f7517e7330115a not found" Sep 12 17:37:14.372145 dockerd[2087]: time="2025-09-12T17:37:14.371732440Z" level=info msg="Loading containers: done." Sep 12 17:37:14.386660 dockerd[2087]: time="2025-09-12T17:37:14.386591783Z" 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 Sep 12 17:37:14.386880 dockerd[2087]: time="2025-09-12T17:37:14.386709589Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 12 17:37:14.386880 dockerd[2087]: time="2025-09-12T17:37:14.386760091Z" level=info msg="Initializing buildkit" Sep 12 17:37:14.403540 dockerd[2087]: time="2025-09-12T17:37:14.403467144Z" level=info msg="Completed buildkit initialization" Sep 12 17:37:14.409829 dockerd[2087]: time="2025-09-12T17:37:14.409738550Z" level=info msg="Daemon has completed initialization" Sep 12 17:37:14.409990 dockerd[2087]: time="2025-09-12T17:37:14.409861019Z" level=info msg="API listen on /run/docker.sock" Sep 12 17:37:14.410100 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 17:37:14.419147 sshd[2141]: Connection closed by 10.0.0.1 port 33812 Sep 12 17:37:14.419572 sshd-session[2138]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:14.428406 systemd[1]: sshd@16-10.0.0.3:22-10.0.0.1:33812.service: Deactivated successfully. Sep 12 17:37:14.430328 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 17:37:14.431200 systemd-logind[1500]: Session 17 logged out. Waiting for processes to exit. Sep 12 17:37:14.434320 systemd[1]: Started sshd@17-10.0.0.3:22-10.0.0.1:57052.service - OpenSSH per-connection server daemon (10.0.0.1:57052). Sep 12 17:37:14.434918 systemd-logind[1500]: Removed session 17. Sep 12 17:37:14.493924 sshd[2393]: Accepted publickey for core from 10.0.0.1 port 57052 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:14.495985 sshd-session[2393]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:14.501768 systemd-logind[1500]: New session 18 of user core. Sep 12 17:37:14.517189 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 17:37:14.519405 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4277543889-merged.mount: Deactivated successfully. Sep 12 17:37:14.652483 systemd[1]: var-lib-docker-overlay2-969291bf874b87baac2911209c130ff99a6927ca2852a14124df1c66f66f938c\x2dinit-merged.mount: Deactivated successfully. Sep 12 17:37:16.336533 containerd[2059]: time="2025-09-12T17:37:16.336409880Z" level=info msg="connecting to shim 872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b" address="unix:///run/containerd/s/5344eff86e3b3b8d400293c87508775f221b3b0c4f66898955c87c31cf9fa8e3" namespace=moby protocol=ttrpc version=3 Sep 12 17:37:16.381095 systemd[1]: Started docker-872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b.scope - libcontainer container 872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b. Sep 12 17:37:16.416316 systemd-resolved[1371]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:37:16.445709 kernel: docker0: port 1(veth6086985) entered blocking state Sep 12 17:37:16.445842 kernel: docker0: port 1(veth6086985) entered disabled state Sep 12 17:37:16.447237 kernel: veth6086985: entered allmulticast mode Sep 12 17:37:16.447279 kernel: veth6086985: entered promiscuous mode Sep 12 17:37:16.448113 systemd-networkd[1408]: veth6086985: Link UP Sep 12 17:37:16.727851 kernel: eth0: renamed from vethc2ad1e8 Sep 12 17:37:16.728008 kernel: docker0: port 1(veth6086985) entered blocking state Sep 12 17:37:16.728036 kernel: docker0: port 1(veth6086985) entered forwarding state Sep 12 17:37:16.728504 systemd-networkd[1408]: veth6086985: Gained carrier Sep 12 17:37:16.728864 systemd-networkd[1408]: docker0: Gained carrier Sep 12 17:37:16.760313 sshd[2396]: Connection closed by 10.0.0.1 port 57052 Sep 12 17:37:16.760895 sshd-session[2393]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:16.778002 systemd[1]: sshd@17-10.0.0.3:22-10.0.0.1:57052.service: Deactivated successfully. Sep 12 17:37:16.780336 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 17:37:16.781352 systemd-logind[1500]: Session 18 logged out. Waiting for processes to exit. Sep 12 17:37:16.784412 systemd[1]: Started sshd@18-10.0.0.3:22-10.0.0.1:57064.service - OpenSSH per-connection server daemon (10.0.0.1:57064). Sep 12 17:37:16.785267 systemd-logind[1500]: Removed session 18. Sep 12 17:37:16.863855 sshd[2462]: Accepted publickey for core from 10.0.0.1 port 57064 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:16.866166 sshd-session[2462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:16.871988 systemd-logind[1500]: New session 19 of user core. Sep 12 17:37:16.886185 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 17:37:16.946912 sshd[2465]: Connection closed by 10.0.0.1 port 57064 Sep 12 17:37:16.947430 sshd-session[2462]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:16.957545 systemd[1]: sshd@18-10.0.0.3:22-10.0.0.1:57064.service: Deactivated successfully. Sep 12 17:37:16.960155 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 17:37:16.961160 systemd-logind[1500]: Session 19 logged out. Waiting for processes to exit. Sep 12 17:37:16.965145 systemd[1]: Started sshd@19-10.0.0.3:22-10.0.0.1:57066.service - OpenSSH per-connection server daemon (10.0.0.1:57066). Sep 12 17:37:16.966363 systemd-logind[1500]: Removed session 19. Sep 12 17:37:17.036649 sshd[2471]: Accepted publickey for core from 10.0.0.1 port 57066 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:17.038527 sshd-session[2471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:17.043839 systemd-logind[1500]: New session 20 of user core. Sep 12 17:37:17.057021 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 17:37:17.112776 sudo[2475]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 2059 Sep 12 17:37:17.113123 sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:37:17.117460 sudo[2475]: pam_unix(sudo:session): session closed for user root Sep 12 17:37:17.119068 sshd[2474]: Connection closed by 10.0.0.1 port 57066 Sep 12 17:37:17.121562 sshd-session[2471]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:17.122717 dockerd[2087]: time="2025-09-12T17:37:17.122636335Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 12 17:37:17.122717 dockerd[2087]: time="2025-09-12T17:37:17.122694159Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 12 17:37:17.123184 dockerd[2087]: time="2025-09-12T17:37:17.122870450Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 12 17:37:17.123184 dockerd[2087]: time="2025-09-12T17:37:17.122907202Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 12 17:37:17.134784 systemd[1]: containerd.service: Deactivated successfully. Sep 12 17:37:17.134986 systemd[1]: containerd.service: Unit process 2415 (containerd-shim) remains running after unit stopped. Sep 12 17:37:17.135310 systemd[1]: containerd.service: Consumed 749ms CPU time, 128.6M memory peak. Sep 12 17:37:17.135836 systemd[1]: sshd@19-10.0.0.3:22-10.0.0.1:57066.service: Deactivated successfully. Sep 12 17:37:17.138092 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 17:37:17.139065 systemd-logind[1500]: Session 20 logged out. Waiting for processes to exit. Sep 12 17:37:17.143866 systemd[1]: Started sshd@20-10.0.0.3:22-10.0.0.1:57072.service - OpenSSH per-connection server daemon (10.0.0.1:57072). Sep 12 17:37:17.144590 systemd-logind[1500]: Removed session 20. Sep 12 17:37:17.212031 sshd[2482]: Accepted publickey for core from 10.0.0.1 port 57072 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:17.214367 sshd-session[2482]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:17.220216 systemd-logind[1500]: New session 21 of user core. Sep 12 17:37:17.230060 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 17:37:17.291669 sshd[2485]: Connection closed by 10.0.0.1 port 57072 Sep 12 17:37:17.291895 sshd-session[2482]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:17.297737 systemd[1]: sshd@20-10.0.0.3:22-10.0.0.1:57072.service: Deactivated successfully. Sep 12 17:37:17.300045 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 17:37:17.301119 systemd-logind[1500]: Session 21 logged out. Waiting for processes to exit. Sep 12 17:37:17.302747 systemd-logind[1500]: Removed session 21. Sep 12 17:37:18.397262 systemd-networkd[1408]: veth6086985: Gained IPv6LL Sep 12 17:37:19.053009 update_engine[1503]: I20250912 17:37:19.052796 1503 update_attempter.cc:509] Updating boot flags... Sep 12 17:37:22.358917 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Sep 12 17:37:22.359482 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 12 17:37:22.359775 dockerd[2087]: time="2025-09-12T17:37:22.359721803Z" level=info msg="Processing signal 'terminated'" Sep 12 17:37:23.319511 systemd[1]: Started sshd@21-10.0.0.3:22-10.0.0.1:46602.service - OpenSSH per-connection server daemon (10.0.0.1:46602). Sep 12 17:37:23.385653 sshd[2502]: Accepted publickey for core from 10.0.0.1 port 46602 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:23.388418 sshd-session[2502]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:23.395632 systemd-logind[1500]: New session 22 of user core. Sep 12 17:37:23.408142 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 17:37:23.469946 sshd[2505]: Connection closed by 10.0.0.1 port 46602 Sep 12 17:37:23.470473 sshd-session[2502]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:23.476060 systemd[1]: sshd@21-10.0.0.3:22-10.0.0.1:46602.service: Deactivated successfully. Sep 12 17:37:23.478895 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 17:37:23.480129 systemd-logind[1500]: Session 22 logged out. Waiting for processes to exit. Sep 12 17:37:23.481721 systemd-logind[1500]: Removed session 22. Sep 12 17:37:24.361871 dockerd[2087]: time="2025-09-12T17:37:24.361707680Z" level=error msg="Error sending stop (signal 15) to container" container=872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b error="Cannot kill container 872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Sep 12 17:37:24.361871 dockerd[2087]: time="2025-09-12T17:37:24.361856064Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b Sep 12 17:37:29.499252 systemd[1]: Started sshd@22-10.0.0.3:22-10.0.0.1:46606.service - OpenSSH per-connection server daemon (10.0.0.1:46606). Sep 12 17:37:29.582171 sshd[2511]: Accepted publickey for core from 10.0.0.1 port 46606 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:29.585472 sshd-session[2511]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:29.591612 systemd-logind[1500]: New session 23 of user core. Sep 12 17:37:29.603259 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 17:37:29.665747 sshd[2514]: Connection closed by 10.0.0.1 port 46606 Sep 12 17:37:29.666187 sshd-session[2511]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:29.671536 systemd[1]: sshd@22-10.0.0.3:22-10.0.0.1:46606.service: Deactivated successfully. Sep 12 17:37:29.673797 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 17:37:29.674943 systemd-logind[1500]: Session 23 logged out. Waiting for processes to exit. Sep 12 17:37:29.676656 systemd-logind[1500]: Removed session 23. Sep 12 17:37:34.362710 dockerd[2087]: time="2025-09-12T17:37:34.362584524Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b error="context deadline exceeded" Sep 12 17:37:34.363892 systemd[1]: docker-872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b.scope: Deactivated successfully. Sep 12 17:37:35.693279 systemd[1]: Started sshd@23-10.0.0.3:22-10.0.0.1:55942.service - OpenSSH per-connection server daemon (10.0.0.1:55942). Sep 12 17:37:35.749848 sshd[2520]: Accepted publickey for core from 10.0.0.1 port 55942 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:35.751183 sshd-session[2520]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:35.756286 systemd-logind[1500]: New session 24 of user core. Sep 12 17:37:35.765952 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 17:37:35.824981 sshd[2523]: Connection closed by 10.0.0.1 port 55942 Sep 12 17:37:35.825390 sshd-session[2520]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:35.829748 systemd[1]: sshd@23-10.0.0.3:22-10.0.0.1:55942.service: Deactivated successfully. Sep 12 17:37:35.832324 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 17:37:35.834291 systemd-logind[1500]: Session 24 logged out. Waiting for processes to exit. Sep 12 17:37:35.836036 systemd-logind[1500]: Removed session 24. Sep 12 17:37:37.360750 dockerd[2087]: time="2025-09-12T17:37:37.360649454Z" level=error msg="Force shutdown daemon" Sep 12 17:37:37.361446 dockerd[2087]: time="2025-09-12T17:37:37.360959096Z" level=info msg="Daemon shutdown complete" Sep 12 17:37:37.370389 systemd[1]: docker.service: Deactivated successfully. Sep 12 17:37:37.371034 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 12 17:37:37.371883 systemd[1]: docker.socket: Deactivated successfully. Sep 12 17:37:37.372483 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 12 17:37:37.372546 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 12 17:37:37.375419 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:37:37.375790 systemd[1]: containerd.service: Found left-over process 2415 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 17:37:37.375803 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 17:37:37.384550 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:37:37.385143 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:37:37.419732 systemd[1]: containerd.service: Found left-over process 2415 (containerd-shim) in control group while starting unit. Ignoring. Sep 12 17:37:37.419747 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 12 17:37:37.442272 (ntainerd)[2534]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:37:37.463290 containerd[2534]: time="2025-09-12T17:37:37Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 17:37:37.463927 containerd[2534]: time="2025-09-12T17:37:37.463892911Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 17:37:37.474199 containerd[2534]: time="2025-09-12T17:37:37.474146541Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="13.338µs" Sep 12 17:37:37.474199 containerd[2534]: time="2025-09-12T17:37:37.474177757Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 17:37:37.474199 containerd[2534]: time="2025-09-12T17:37:37.474195646Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 17:37:37.474357 containerd[2534]: time="2025-09-12T17:37:37.474231332Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 17:37:37.474357 containerd[2534]: time="2025-09-12T17:37:37.474243097Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 17:37:37.474497 containerd[2534]: time="2025-09-12T17:37:37.474466544Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474540 containerd[2534]: time="2025-09-12T17:37:37.474496378Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474540 containerd[2534]: time="2025-09-12T17:37:37.474507011Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474794 containerd[2534]: time="2025-09-12T17:37:37.474764512Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474794 containerd[2534]: time="2025-09-12T17:37:37.474779564Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474794 containerd[2534]: time="2025-09-12T17:37:37.474789315Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474920 containerd[2534]: time="2025-09-12T17:37:37.474797822Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474920 containerd[2534]: time="2025-09-12T17:37:37.474836286Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474973 containerd[2534]: time="2025-09-12T17:37:37.474943655Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:37:37.474973 containerd[2534]: time="2025-09-12T17:37:37.474966824Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:37:37.475024 containerd[2534]: time="2025-09-12T17:37:37.474975843Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 17:37:37.475024 containerd[2534]: time="2025-09-12T17:37:37.475012051Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 17:37:37.475364 containerd[2534]: time="2025-09-12T17:37:37.475293933Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 17:37:37.475432 containerd[2534]: time="2025-09-12T17:37:37.475403969Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:37:37.475749 containerd[2534]: time="2025-09-12T17:37:37.475707788Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 17:37:37.475782 containerd[2534]: time="2025-09-12T17:37:37.475760170Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 17:37:37.475829 containerd[2534]: time="2025-09-12T17:37:37.475782318Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 17:37:37.475829 containerd[2534]: time="2025-09-12T17:37:37.475801218Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 17:37:37.475901 containerd[2534]: time="2025-09-12T17:37:37.475849491Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 17:37:37.475901 containerd[2534]: time="2025-09-12T17:37:37.475871127Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 17:37:37.475901 containerd[2534]: time="2025-09-12T17:37:37.475890569Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 17:37:37.475988 containerd[2534]: time="2025-09-12T17:37:37.475909990Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 17:37:37.475988 containerd[2534]: time="2025-09-12T17:37:37.475929311Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 17:37:37.475988 containerd[2534]: time="2025-09-12T17:37:37.475945557Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 17:37:37.475988 containerd[2534]: time="2025-09-12T17:37:37.475961821Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 17:37:37.475988 containerd[2534]: time="2025-09-12T17:37:37.475984199Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 17:37:37.476465 containerd[2534]: time="2025-09-12T17:37:37.476417636Z" level=info msg="connecting to shim 872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b" address="unix:///run/containerd/s/5344eff86e3b3b8d400293c87508775f221b3b0c4f66898955c87c31cf9fa8e3" namespace=moby protocol=ttrpc version=3 Sep 12 17:37:37.486360 containerd[2534]: time="2025-09-12T17:37:37.486305724Z" level=info msg="cleaning leaked shim process" id=872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b namespace=moby Sep 12 17:37:37.498186 containerd[2534]: time="2025-09-12T17:37:37.498062997Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Sep 12 17:37:37.498915 containerd[2534]: time="2025-09-12T17:37:37.498871536Z" level=info msg="shim disconnected" id=872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b namespace=moby Sep 12 17:37:37.498915 containerd[2534]: time="2025-09-12T17:37:37.498916332Z" level=warning msg="cleaning up after shim disconnected" id=872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b namespace=moby Sep 12 17:37:37.499016 containerd[2534]: time="2025-09-12T17:37:37.498926674Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 17:37:37.513773 containerd[2534]: time="2025-09-12T17:37:37.513710477Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 17:37:37.513773 containerd[2534]: time="2025-09-12T17:37:37.513788334Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 17:37:37.513989 containerd[2534]: time="2025-09-12T17:37:37.513812746Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 17:37:37.513989 containerd[2534]: time="2025-09-12T17:37:37.513931059Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 17:37:37.513989 containerd[2534]: time="2025-09-12T17:37:37.513945219Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 17:37:37.513989 containerd[2534]: time="2025-09-12T17:37:37.513957596Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 17:37:37.513989 containerd[2534]: time="2025-09-12T17:37:37.513970895Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 17:37:37.513989 containerd[2534]: time="2025-09-12T17:37:37.513984132Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 17:37:37.514186 containerd[2534]: time="2025-09-12T17:37:37.513998313Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 17:37:37.514186 containerd[2534]: time="2025-09-12T17:37:37.514011661Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 17:37:37.514186 containerd[2534]: time="2025-09-12T17:37:37.514025160Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 17:37:37.514186 containerd[2534]: time="2025-09-12T17:37:37.514122960Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 17:37:37.514186 containerd[2534]: time="2025-09-12T17:37:37.514140326Z" level=info msg="Start snapshots syncer" Sep 12 17:37:37.514186 containerd[2534]: time="2025-09-12T17:37:37.514181094Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 17:37:37.514443 containerd[2534]: time="2025-09-12T17:37:37.514406325Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 17:37:37.514611 containerd[2534]: time="2025-09-12T17:37:37.514473198Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 17:37:37.514611 containerd[2534]: time="2025-09-12T17:37:37.514567740Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 17:37:37.514675 containerd[2534]: time="2025-09-12T17:37:37.514617256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 17:37:37.514675 containerd[2534]: time="2025-09-12T17:37:37.514635926Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 17:37:37.514675 containerd[2534]: time="2025-09-12T17:37:37.514646228Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 17:37:37.514675 containerd[2534]: time="2025-09-12T17:37:37.514657241Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 17:37:37.514675 containerd[2534]: time="2025-09-12T17:37:37.514668806Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 17:37:37.514675 containerd[2534]: time="2025-09-12T17:37:37.514679449Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514691404Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514732522Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514746402Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514757596Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514795868Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514810900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514839020Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514848972Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514856728Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514867511Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 17:37:37.514871 containerd[2534]: time="2025-09-12T17:37:37.514880008Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 17:37:37.515191 containerd[2534]: time="2025-09-12T17:37:37.514899359Z" level=info msg="runtime interface created" Sep 12 17:37:37.515191 containerd[2534]: time="2025-09-12T17:37:37.514905292Z" level=info msg="created NRI interface" Sep 12 17:37:37.515191 containerd[2534]: time="2025-09-12T17:37:37.514914481Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 17:37:37.515191 containerd[2534]: time="2025-09-12T17:37:37.514925565Z" level=info msg="Connect containerd service" Sep 12 17:37:37.515191 containerd[2534]: time="2025-09-12T17:37:37.514953214Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:37:37.516421 containerd[2534]: time="2025-09-12T17:37:37.516183624Z" 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" Sep 12 17:37:37.529479 containerd[2534]: time="2025-09-12T17:37:37.529398023Z" level=info msg="Start subscribing containerd event" Sep 12 17:37:37.529572 containerd[2534]: time="2025-09-12T17:37:37.529471120Z" level=info msg="Start recovering state" Sep 12 17:37:37.529612 containerd[2534]: time="2025-09-12T17:37:37.529595796Z" level=info msg="Start event monitor" Sep 12 17:37:37.529641 containerd[2534]: time="2025-09-12T17:37:37.529619427Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:37:37.529641 containerd[2534]: time="2025-09-12T17:37:37.529632745Z" level=info msg="Start streaming server" Sep 12 17:37:37.529692 containerd[2534]: time="2025-09-12T17:37:37.529646174Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 17:37:37.529692 containerd[2534]: time="2025-09-12T17:37:37.529652617Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:37:37.529888 containerd[2534]: time="2025-09-12T17:37:37.529656335Z" level=info msg="runtime interface starting up..." Sep 12 17:37:37.529888 containerd[2534]: time="2025-09-12T17:37:37.529733521Z" level=info msg="starting plugins..." Sep 12 17:37:37.529888 containerd[2534]: time="2025-09-12T17:37:37.529750436Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:37:37.529888 containerd[2534]: time="2025-09-12T17:37:37.529752141Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 17:37:37.529998 containerd[2534]: time="2025-09-12T17:37:37.529966088Z" level=info msg="containerd successfully booted in 0.066949s" Sep 12 17:37:37.530132 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:37:37.532443 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 17:37:37.555383 (dockerd)[2581]: 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 Sep 12 17:37:37.596958 dockerd[2581]: time="2025-09-12T17:37:37.596832048Z" level=info msg="Starting up" Sep 12 17:37:37.597938 dockerd[2581]: time="2025-09-12T17:37:37.597881412Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 12 17:37:37.612558 dockerd[2581]: time="2025-09-12T17:37:37.612378112Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 12 17:37:37.635858 dockerd[2581]: time="2025-09-12T17:37:37.635766570Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 12 17:37:37.661452 dockerd[2581]: time="2025-09-12T17:37:37.661384184Z" level=info msg="Loading containers: start." Sep 12 17:37:37.679656 systemd[1]: var-lib-docker-overlay2-969291bf874b87baac2911209c130ff99a6927ca2852a14124df1c66f66f938c-merged.mount: Deactivated successfully. Sep 12 17:37:38.192684 systemd-networkd[1408]: veth6086985: Link DOWN Sep 12 17:37:38.193246 kernel: docker0: port 1(veth6086985) entered disabled state Sep 12 17:37:38.192694 systemd-networkd[1408]: veth6086985: Lost carrier Sep 12 17:37:38.195136 kernel: veth6086985 (unregistering): left allmulticast mode Sep 12 17:37:38.195187 kernel: veth6086985 (unregistering): left promiscuous mode Sep 12 17:37:38.195218 kernel: docker0: port 1(veth6086985) entered disabled state Sep 12 17:37:38.197063 systemd-networkd[1408]: docker0: Lost carrier Sep 12 17:37:38.199940 dockerd[2581]: time="2025-09-12T17:37:38.199897399Z" level=info msg="Removing stale sandbox cfa6d29c816b2eae58c3a9479bf587626a0e38740ff7443ff9f6740b51138269 (872c9ff8a5298d23572f91580e210d6b5183a55cbd69ca84b219af26c503967b)" Sep 12 17:37:38.206679 dockerd[2581]: time="2025-09-12T17:37:38.206636338Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 12 17:37:38.206748 dockerd[2581]: time="2025-09-12T17:37:38.206681073Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint f6701b455855dcb53ed9560edc53152e6e6fa600a415e9097d6fc033854588a5 330cb1b206b290f256ceb5797c7da1b431ed90716617f8601df1902da6439556], retrying...." Sep 12 17:37:38.399326 dockerd[2581]: time="2025-09-12T17:37:38.399245421Z" level=warning msg="error locating sandbox id cfa6d29c816b2eae58c3a9479bf587626a0e38740ff7443ff9f6740b51138269: sandbox cfa6d29c816b2eae58c3a9479bf587626a0e38740ff7443ff9f6740b51138269 not found" Sep 12 17:37:38.399326 dockerd[2581]: time="2025-09-12T17:37:38.399295748Z" level=warning msg="error locating sandbox id 1eb699929be323fc8de8b74c03b294ade518e4679edb284156f7517e7330115a: sandbox 1eb699929be323fc8de8b74c03b294ade518e4679edb284156f7517e7330115a not found" Sep 12 17:37:38.399528 dockerd[2581]: time="2025-09-12T17:37:38.399434192Z" level=info msg="Loading containers: done." Sep 12 17:37:38.414515 dockerd[2581]: time="2025-09-12T17:37:38.414449804Z" 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 Sep 12 17:37:38.414724 dockerd[2581]: time="2025-09-12T17:37:38.414568396Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 12 17:37:38.414724 dockerd[2581]: time="2025-09-12T17:37:38.414619595Z" level=info msg="Initializing buildkit" Sep 12 17:37:38.431574 dockerd[2581]: time="2025-09-12T17:37:38.431526984Z" level=info msg="Completed buildkit initialization" Sep 12 17:37:38.438070 dockerd[2581]: time="2025-09-12T17:37:38.438024288Z" level=info msg="Daemon has completed initialization" Sep 12 17:37:38.438192 dockerd[2581]: time="2025-09-12T17:37:38.438117065Z" level=info msg="API listen on /run/docker.sock" Sep 12 17:37:38.438365 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 17:37:38.622708 systemd[1]: run-docker-netns-cfa6d29c816b.mount: Deactivated successfully. Sep 12 17:37:41.851321 systemd[1]: Started sshd@24-10.0.0.3:22-10.0.0.1:59420.service - OpenSSH per-connection server daemon (10.0.0.1:59420). Sep 12 17:37:41.911623 sshd[2818]: Accepted publickey for core from 10.0.0.1 port 59420 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:41.914032 sshd-session[2818]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:41.920399 systemd-logind[1500]: New session 25 of user core. Sep 12 17:37:41.930203 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 17:37:41.990425 sshd[2821]: Connection closed by 10.0.0.1 port 59420 Sep 12 17:37:41.990968 sshd-session[2818]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:42.011034 systemd[1]: sshd@24-10.0.0.3:22-10.0.0.1:59420.service: Deactivated successfully. Sep 12 17:37:42.013704 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 17:37:42.014738 systemd-logind[1500]: Session 25 logged out. Waiting for processes to exit. Sep 12 17:37:42.019375 systemd[1]: Started sshd@25-10.0.0.3:22-10.0.0.1:59430.service - OpenSSH per-connection server daemon (10.0.0.1:59430). Sep 12 17:37:42.020099 systemd-logind[1500]: Removed session 25. Sep 12 17:37:42.085143 sshd[2827]: Accepted publickey for core from 10.0.0.1 port 59430 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:42.087072 sshd-session[2827]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:42.092724 systemd-logind[1500]: New session 26 of user core. Sep 12 17:37:42.102181 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 17:37:42.163549 sshd[2830]: Connection closed by 10.0.0.1 port 59430 Sep 12 17:37:42.163965 sshd-session[2827]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:42.178337 systemd[1]: sshd@25-10.0.0.3:22-10.0.0.1:59430.service: Deactivated successfully. Sep 12 17:37:42.180658 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 17:37:42.181789 systemd-logind[1500]: Session 26 logged out. Waiting for processes to exit. Sep 12 17:37:42.184901 systemd[1]: Started sshd@26-10.0.0.3:22-10.0.0.1:59446.service - OpenSSH per-connection server daemon (10.0.0.1:59446). Sep 12 17:37:42.185613 systemd-logind[1500]: Removed session 26. Sep 12 17:37:42.248354 sshd[2836]: Accepted publickey for core from 10.0.0.1 port 59446 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:42.250282 sshd-session[2836]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:42.255593 systemd-logind[1500]: New session 27 of user core. Sep 12 17:37:42.266997 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 17:37:42.334183 sshd[2840]: Connection closed by 10.0.0.1 port 59446 Sep 12 17:37:42.334667 sshd-session[2836]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:42.345787 systemd[1]: sshd@26-10.0.0.3:22-10.0.0.1:59446.service: Deactivated successfully. Sep 12 17:37:42.348550 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 17:37:42.349532 systemd-logind[1500]: Session 27 logged out. Waiting for processes to exit. Sep 12 17:37:42.353596 systemd[1]: Started sshd@27-10.0.0.3:22-10.0.0.1:59448.service - OpenSSH per-connection server daemon (10.0.0.1:59448). Sep 12 17:37:42.355255 systemd-logind[1500]: Removed session 27. Sep 12 17:37:42.411927 sshd[2852]: Accepted publickey for core from 10.0.0.1 port 59448 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:37:42.413559 sshd-session[2852]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:37:42.418472 systemd-logind[1500]: New session 28 of user core. Sep 12 17:37:42.436083 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 17:37:43.010943 systemd[1]: var-lib-docker-overlay2-6099651dde892af92ef674ccb95cae20de6f42e640458438985ee5a2f814c35f\x2dinit-merged.mount: Deactivated successfully. Sep 12 17:37:43.067403 containerd[2534]: time="2025-09-12T17:37:43.067352586Z" level=info msg="connecting to shim cf23aa1c1a347a599e8ca4ec55dd9e601909a552b9909759dbe38fdf84c409ed" address="unix:///run/containerd/s/e4b358ceedf3f571b8242c8331f4cde09cd17b70d7f6437a10e3fba0bdc6613b" namespace=moby protocol=ttrpc version=3 Sep 12 17:37:43.093038 systemd[1]: Started docker-cf23aa1c1a347a599e8ca4ec55dd9e601909a552b9909759dbe38fdf84c409ed.scope - libcontainer container cf23aa1c1a347a599e8ca4ec55dd9e601909a552b9909759dbe38fdf84c409ed. Sep 12 17:37:43.111154 systemd-resolved[1371]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:37:43.140415 kernel: docker0: port 1(veth24359b2) entered blocking state Sep 12 17:37:43.140553 kernel: docker0: port 1(veth24359b2) entered disabled state Sep 12 17:37:43.140577 kernel: veth24359b2: entered allmulticast mode Sep 12 17:37:43.142077 kernel: veth24359b2: entered promiscuous mode Sep 12 17:37:43.142411 systemd-networkd[1408]: veth24359b2: Link UP Sep 12 17:37:43.150859 kernel: eth0: renamed from veth4adc130 Sep 12 17:37:43.153347 kernel: docker0: port 1(veth24359b2) entered blocking state Sep 12 17:37:43.153443 kernel: docker0: port 1(veth24359b2) entered forwarding state Sep 12 17:37:43.153383 systemd-networkd[1408]: veth24359b2: Gained carrier Sep 12 17:37:43.154381 systemd-networkd[1408]: docker0: Gained carrier Sep 12 17:37:43.190444 sshd[2855]: Connection closed by 10.0.0.1 port 59448 Sep 12 17:37:43.190883 sshd-session[2852]: pam_unix(sshd:session): session closed for user core Sep 12 17:37:43.196459 systemd[1]: sshd@27-10.0.0.3:22-10.0.0.1:59448.service: Deactivated successfully. Sep 12 17:37:43.198642 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 17:37:43.199603 systemd-logind[1500]: Session 28 logged out. Waiting for processes to exit. Sep 12 17:37:43.201425 systemd-logind[1500]: Removed session 28.