Sep 4 16:16:42.018011 kernel: Linux version 6.12.44-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 Thu Sep 4 14:31:01 -00 2025 Sep 4 16:16:42.018052 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=39929ed91cc8dec12f10b74359379a21a9960032f4b779521fabb4147461485b Sep 4 16:16:42.018062 kernel: BIOS-provided physical RAM map: Sep 4 16:16:42.018069 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 4 16:16:42.018075 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Sep 4 16:16:42.018082 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Sep 4 16:16:42.018090 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Sep 4 16:16:42.018097 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Sep 4 16:16:42.018107 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable Sep 4 16:16:42.018114 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Sep 4 16:16:42.018121 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable Sep 4 16:16:42.018128 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Sep 4 16:16:42.018134 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable Sep 4 16:16:42.018141 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Sep 4 16:16:42.018162 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Sep 4 16:16:42.018172 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Sep 4 16:16:42.018190 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce90fff] usable Sep 4 16:16:42.018199 kernel: BIOS-e820: [mem 0x000000009ce91000-0x000000009ce94fff] reserved Sep 4 16:16:42.018206 kernel: BIOS-e820: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS Sep 4 16:16:42.018214 kernel: BIOS-e820: [mem 0x000000009ce97000-0x000000009cedbfff] usable Sep 4 16:16:42.018221 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Sep 4 16:16:42.018228 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Sep 4 16:16:42.018239 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 4 16:16:42.018246 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 4 16:16:42.018254 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Sep 4 16:16:42.018261 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 4 16:16:42.018268 kernel: NX (Execute Disable) protection: active Sep 4 16:16:42.018276 kernel: APIC: Static calls initialized Sep 4 16:16:42.018283 kernel: e820: update [mem 0x9b320018-0x9b329c57] usable ==> usable Sep 4 16:16:42.018291 kernel: e820: update [mem 0x9b2e3018-0x9b31fe57] usable ==> usable Sep 4 16:16:42.018298 kernel: extended physical RAM map: Sep 4 16:16:42.018305 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 4 16:16:42.018313 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable Sep 4 16:16:42.018322 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Sep 4 16:16:42.018330 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable Sep 4 16:16:42.018337 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Sep 4 16:16:42.018344 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable Sep 4 16:16:42.018352 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Sep 4 16:16:42.018359 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b2e3017] usable Sep 4 16:16:42.018367 kernel: reserve setup_data: [mem 0x000000009b2e3018-0x000000009b31fe57] usable Sep 4 16:16:42.018379 kernel: reserve setup_data: [mem 0x000000009b31fe58-0x000000009b320017] usable Sep 4 16:16:42.018387 kernel: reserve setup_data: [mem 0x000000009b320018-0x000000009b329c57] usable Sep 4 16:16:42.018395 kernel: reserve setup_data: [mem 0x000000009b329c58-0x000000009bd3efff] usable Sep 4 16:16:42.018402 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Sep 4 16:16:42.018410 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable Sep 4 16:16:42.018418 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Sep 4 16:16:42.018425 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Sep 4 16:16:42.018435 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Sep 4 16:16:42.018443 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce90fff] usable Sep 4 16:16:42.018451 kernel: reserve setup_data: [mem 0x000000009ce91000-0x000000009ce94fff] reserved Sep 4 16:16:42.018459 kernel: reserve setup_data: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS Sep 4 16:16:42.018466 kernel: reserve setup_data: [mem 0x000000009ce97000-0x000000009cedbfff] usable Sep 4 16:16:42.018474 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Sep 4 16:16:42.018482 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Sep 4 16:16:42.018489 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 4 16:16:42.018497 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 4 16:16:42.018504 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Sep 4 16:16:42.018514 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 4 16:16:42.018522 kernel: efi: EFI v2.7 by EDK II Sep 4 16:16:42.018530 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b9e4198 RNG=0x9cb73018 Sep 4 16:16:42.018537 kernel: random: crng init done Sep 4 16:16:42.018548 kernel: efi: Remove mem151: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Sep 4 16:16:42.018556 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Sep 4 16:16:42.018565 kernel: secureboot: Secure boot disabled Sep 4 16:16:42.018573 kernel: SMBIOS 2.8 present. Sep 4 16:16:42.018581 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Sep 4 16:16:42.018589 kernel: DMI: Memory slots populated: 1/1 Sep 4 16:16:42.018596 kernel: Hypervisor detected: KVM Sep 4 16:16:42.018606 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 4 16:16:42.018614 kernel: kvm-clock: using sched offset of 4830238965 cycles Sep 4 16:16:42.018622 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 4 16:16:42.018630 kernel: tsc: Detected 2794.748 MHz processor Sep 4 16:16:42.018639 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 4 16:16:42.018647 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 4 16:16:42.018655 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Sep 4 16:16:42.018663 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 4 16:16:42.018674 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 4 16:16:42.018682 kernel: Using GB pages for direct mapping Sep 4 16:16:42.018690 kernel: ACPI: Early table checksum verification disabled Sep 4 16:16:42.018698 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Sep 4 16:16:42.018706 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 4 16:16:42.018715 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 16:16:42.018730 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 16:16:42.018738 kernel: ACPI: FACS 0x000000009CBDD000 000040 Sep 4 16:16:42.018748 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 16:16:42.018756 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 16:16:42.018765 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 16:16:42.018773 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 16:16:42.018781 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 4 16:16:42.018789 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Sep 4 16:16:42.018797 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Sep 4 16:16:42.018807 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Sep 4 16:16:42.018815 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Sep 4 16:16:42.018824 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Sep 4 16:16:42.018832 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Sep 4 16:16:42.018840 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Sep 4 16:16:42.018847 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Sep 4 16:16:42.018855 kernel: No NUMA configuration found Sep 4 16:16:42.018865 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] Sep 4 16:16:42.018887 kernel: NODE_DATA(0) allocated [mem 0x9ce36dc0-0x9ce3dfff] Sep 4 16:16:42.018895 kernel: Zone ranges: Sep 4 16:16:42.018904 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 4 16:16:42.018912 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] Sep 4 16:16:42.018920 kernel: Normal empty Sep 4 16:16:42.018928 kernel: Device empty Sep 4 16:16:42.018936 kernel: Movable zone start for each node Sep 4 16:16:42.018946 kernel: Early memory node ranges Sep 4 16:16:42.018954 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 4 16:16:42.018962 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Sep 4 16:16:42.018973 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Sep 4 16:16:42.018981 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] Sep 4 16:16:42.018989 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] Sep 4 16:16:42.018997 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] Sep 4 16:16:42.019007 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce90fff] Sep 4 16:16:42.019015 kernel: node 0: [mem 0x000000009ce97000-0x000000009cedbfff] Sep 4 16:16:42.019023 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] Sep 4 16:16:42.019031 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 16:16:42.019040 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 4 16:16:42.019054 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Sep 4 16:16:42.019065 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 16:16:42.019073 kernel: On node 0, zone DMA: 239 pages in unavailable ranges Sep 4 16:16:42.019082 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Sep 4 16:16:42.019090 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Sep 4 16:16:42.019101 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Sep 4 16:16:42.019109 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges Sep 4 16:16:42.019118 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 4 16:16:42.019126 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 4 16:16:42.019136 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 4 16:16:42.019145 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 4 16:16:42.019153 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 4 16:16:42.019161 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 4 16:16:42.019170 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 4 16:16:42.019178 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 4 16:16:42.019186 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 4 16:16:42.019196 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 4 16:16:42.019205 kernel: TSC deadline timer available Sep 4 16:16:42.019213 kernel: CPU topo: Max. logical packages: 1 Sep 4 16:16:42.019221 kernel: CPU topo: Max. logical dies: 1 Sep 4 16:16:42.019229 kernel: CPU topo: Max. dies per package: 1 Sep 4 16:16:42.019237 kernel: CPU topo: Max. threads per core: 1 Sep 4 16:16:42.019246 kernel: CPU topo: Num. cores per package: 4 Sep 4 16:16:42.019256 kernel: CPU topo: Num. threads per package: 4 Sep 4 16:16:42.019264 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 4 16:16:42.019272 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 4 16:16:42.019281 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 4 16:16:42.019289 kernel: kvm-guest: setup PV sched yield Sep 4 16:16:42.019297 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Sep 4 16:16:42.019306 kernel: Booting paravirtualized kernel on KVM Sep 4 16:16:42.019314 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 4 16:16:42.019325 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 4 16:16:42.019333 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 4 16:16:42.019341 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 4 16:16:42.019350 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 4 16:16:42.019358 kernel: kvm-guest: PV spinlocks enabled Sep 4 16:16:42.019366 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 4 16:16:42.019378 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=39929ed91cc8dec12f10b74359379a21a9960032f4b779521fabb4147461485b Sep 4 16:16:42.019390 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 16:16:42.019398 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 16:16:42.019407 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 16:16:42.019415 kernel: Fallback order for Node 0: 0 Sep 4 16:16:42.019423 kernel: Built 1 zonelists, mobility grouping on. Total pages: 641450 Sep 4 16:16:42.019432 kernel: Policy zone: DMA32 Sep 4 16:16:42.019442 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 16:16:42.019450 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 4 16:16:42.019459 kernel: ftrace: allocating 40102 entries in 157 pages Sep 4 16:16:42.019467 kernel: ftrace: allocated 157 pages with 5 groups Sep 4 16:16:42.019476 kernel: Dynamic Preempt: voluntary Sep 4 16:16:42.019484 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 16:16:42.019493 kernel: rcu: RCU event tracing is enabled. Sep 4 16:16:42.019503 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 4 16:16:42.019512 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 16:16:42.019520 kernel: Rude variant of Tasks RCU enabled. Sep 4 16:16:42.019529 kernel: Tracing variant of Tasks RCU enabled. Sep 4 16:16:42.019537 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 16:16:42.019546 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 4 16:16:42.019554 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 16:16:42.019562 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 16:16:42.019573 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 16:16:42.019581 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 4 16:16:42.019590 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 16:16:42.019598 kernel: Console: colour dummy device 80x25 Sep 4 16:16:42.019606 kernel: printk: legacy console [ttyS0] enabled Sep 4 16:16:42.019614 kernel: ACPI: Core revision 20240827 Sep 4 16:16:42.019623 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 4 16:16:42.019633 kernel: APIC: Switch to symmetric I/O mode setup Sep 4 16:16:42.019641 kernel: x2apic enabled Sep 4 16:16:42.019649 kernel: APIC: Switched APIC routing to: physical x2apic Sep 4 16:16:42.019658 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 4 16:16:42.019666 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 4 16:16:42.019675 kernel: kvm-guest: setup PV IPIs Sep 4 16:16:42.019683 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 4 16:16:42.019694 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Sep 4 16:16:42.019702 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Sep 4 16:16:42.019710 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 4 16:16:42.019726 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 4 16:16:42.019734 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 4 16:16:42.019743 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 4 16:16:42.019751 kernel: Spectre V2 : Mitigation: Retpolines Sep 4 16:16:42.019762 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 4 16:16:42.019770 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 4 16:16:42.019779 kernel: active return thunk: retbleed_return_thunk Sep 4 16:16:42.019787 kernel: RETBleed: Mitigation: untrained return thunk Sep 4 16:16:42.019798 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 4 16:16:42.019806 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 4 16:16:42.019814 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 4 16:16:42.019826 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 4 16:16:42.019834 kernel: active return thunk: srso_return_thunk Sep 4 16:16:42.019843 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 4 16:16:42.019851 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 4 16:16:42.019859 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 4 16:16:42.019868 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 4 16:16:42.019900 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 4 16:16:42.019909 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 4 16:16:42.019917 kernel: Freeing SMP alternatives memory: 32K Sep 4 16:16:42.019926 kernel: pid_max: default: 32768 minimum: 301 Sep 4 16:16:42.019934 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 4 16:16:42.019942 kernel: landlock: Up and running. Sep 4 16:16:42.019951 kernel: SELinux: Initializing. Sep 4 16:16:42.019959 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 16:16:42.019979 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 16:16:42.019989 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 4 16:16:42.020006 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 4 16:16:42.020024 kernel: ... version: 0 Sep 4 16:16:42.020033 kernel: ... bit width: 48 Sep 4 16:16:42.020041 kernel: ... generic registers: 6 Sep 4 16:16:42.020049 kernel: ... value mask: 0000ffffffffffff Sep 4 16:16:42.020061 kernel: ... max period: 00007fffffffffff Sep 4 16:16:42.020069 kernel: ... fixed-purpose events: 0 Sep 4 16:16:42.020077 kernel: ... event mask: 000000000000003f Sep 4 16:16:42.020086 kernel: signal: max sigframe size: 1776 Sep 4 16:16:42.020094 kernel: rcu: Hierarchical SRCU implementation. Sep 4 16:16:42.020102 kernel: rcu: Max phase no-delay instances is 400. Sep 4 16:16:42.020111 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 4 16:16:42.020122 kernel: smp: Bringing up secondary CPUs ... Sep 4 16:16:42.020130 kernel: smpboot: x86: Booting SMP configuration: Sep 4 16:16:42.020138 kernel: .... node #0, CPUs: #1 #2 #3 Sep 4 16:16:42.020147 kernel: smp: Brought up 1 node, 4 CPUs Sep 4 16:16:42.020155 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Sep 4 16:16:42.020164 kernel: Memory: 2422672K/2565800K available (14336K kernel code, 2428K rwdata, 9988K rodata, 54288K init, 2680K bss, 137200K reserved, 0K cma-reserved) Sep 4 16:16:42.020172 kernel: devtmpfs: initialized Sep 4 16:16:42.020182 kernel: x86/mm: Memory block size: 128MB Sep 4 16:16:42.020191 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Sep 4 16:16:42.020199 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Sep 4 16:16:42.020208 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) Sep 4 16:16:42.020216 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Sep 4 16:16:42.020225 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce95000-0x9ce96fff] (8192 bytes) Sep 4 16:16:42.020233 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Sep 4 16:16:42.020244 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 16:16:42.020252 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 4 16:16:42.020261 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 16:16:42.020269 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 16:16:42.020278 kernel: audit: initializing netlink subsys (disabled) Sep 4 16:16:42.020286 kernel: audit: type=2000 audit(1757002598.479:1): state=initialized audit_enabled=0 res=1 Sep 4 16:16:42.020295 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 16:16:42.020305 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 4 16:16:42.020313 kernel: cpuidle: using governor menu Sep 4 16:16:42.020322 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 16:16:42.020330 kernel: dca service started, version 1.12.1 Sep 4 16:16:42.020338 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Sep 4 16:16:42.020347 kernel: PCI: Using configuration type 1 for base access Sep 4 16:16:42.020355 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 4 16:16:42.020366 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 16:16:42.020374 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 16:16:42.020382 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 16:16:42.020391 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 16:16:42.020399 kernel: ACPI: Added _OSI(Module Device) Sep 4 16:16:42.020407 kernel: ACPI: Added _OSI(Processor Device) Sep 4 16:16:42.020416 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 16:16:42.020426 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 16:16:42.020435 kernel: ACPI: Interpreter enabled Sep 4 16:16:42.020443 kernel: ACPI: PM: (supports S0 S3 S5) Sep 4 16:16:42.020451 kernel: ACPI: Using IOAPIC for interrupt routing Sep 4 16:16:42.020459 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 4 16:16:42.020468 kernel: PCI: Using E820 reservations for host bridge windows Sep 4 16:16:42.020477 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 4 16:16:42.020487 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 4 16:16:42.020810 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 4 16:16:42.021007 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 4 16:16:42.021180 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 4 16:16:42.021192 kernel: PCI host bridge to bus 0000:00 Sep 4 16:16:42.021385 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 4 16:16:42.021547 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 4 16:16:42.021701 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 4 16:16:42.021869 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Sep 4 16:16:42.022131 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Sep 4 16:16:42.022285 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Sep 4 16:16:42.022444 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 4 16:16:42.022640 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 4 16:16:42.022863 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 4 16:16:42.023050 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Sep 4 16:16:42.023219 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Sep 4 16:16:42.023396 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Sep 4 16:16:42.023566 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 4 16:16:42.023764 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 4 16:16:42.023951 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Sep 4 16:16:42.024121 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Sep 4 16:16:42.024287 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref] Sep 4 16:16:42.024467 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 4 16:16:42.024640 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Sep 4 16:16:42.024815 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Sep 4 16:16:42.025001 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref] Sep 4 16:16:42.025203 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 4 16:16:42.025372 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Sep 4 16:16:42.025544 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Sep 4 16:16:42.025711 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref] Sep 4 16:16:42.025909 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Sep 4 16:16:42.026127 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 4 16:16:42.026319 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 4 16:16:42.026562 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 4 16:16:42.026810 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Sep 4 16:16:42.027072 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Sep 4 16:16:42.027320 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 4 16:16:42.027531 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Sep 4 16:16:42.027550 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 4 16:16:42.027567 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 4 16:16:42.027579 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 4 16:16:42.027590 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 4 16:16:42.027601 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 4 16:16:42.027612 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 4 16:16:42.027622 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 4 16:16:42.027634 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 4 16:16:42.027650 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 4 16:16:42.027661 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 4 16:16:42.027671 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 4 16:16:42.027682 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 4 16:16:42.027692 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 4 16:16:42.027703 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 4 16:16:42.027714 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 4 16:16:42.027735 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 4 16:16:42.027750 kernel: iommu: Default domain type: Translated Sep 4 16:16:42.027761 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 4 16:16:42.027773 kernel: efivars: Registered efivars operations Sep 4 16:16:42.027784 kernel: PCI: Using ACPI for IRQ routing Sep 4 16:16:42.027795 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 4 16:16:42.027806 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Sep 4 16:16:42.027817 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] Sep 4 16:16:42.027831 kernel: e820: reserve RAM buffer [mem 0x9b2e3018-0x9bffffff] Sep 4 16:16:42.027842 kernel: e820: reserve RAM buffer [mem 0x9b320018-0x9bffffff] Sep 4 16:16:42.027853 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] Sep 4 16:16:42.027864 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] Sep 4 16:16:42.027895 kernel: e820: reserve RAM buffer [mem 0x9ce91000-0x9fffffff] Sep 4 16:16:42.027904 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] Sep 4 16:16:42.028107 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 4 16:16:42.028279 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 4 16:16:42.028452 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 4 16:16:42.028464 kernel: vgaarb: loaded Sep 4 16:16:42.028473 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 4 16:16:42.028482 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 4 16:16:42.028490 kernel: clocksource: Switched to clocksource kvm-clock Sep 4 16:16:42.028499 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 16:16:42.028508 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 16:16:42.028520 kernel: pnp: PnP ACPI init Sep 4 16:16:42.028769 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Sep 4 16:16:42.028788 kernel: pnp: PnP ACPI: found 6 devices Sep 4 16:16:42.028797 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 4 16:16:42.028806 kernel: NET: Registered PF_INET protocol family Sep 4 16:16:42.028815 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 16:16:42.028825 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 16:16:42.028836 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 16:16:42.028846 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 16:16:42.028858 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 16:16:42.028870 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 16:16:42.028906 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 16:16:42.028918 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 16:16:42.028931 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 16:16:42.028947 kernel: NET: Registered PF_XDP protocol family Sep 4 16:16:42.029170 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Sep 4 16:16:42.029392 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Sep 4 16:16:42.029597 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 4 16:16:42.029798 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 4 16:16:42.030008 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 4 16:16:42.030219 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Sep 4 16:16:42.030431 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Sep 4 16:16:42.030637 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Sep 4 16:16:42.030656 kernel: PCI: CLS 0 bytes, default 64 Sep 4 16:16:42.030670 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Sep 4 16:16:42.030682 kernel: Initialise system trusted keyrings Sep 4 16:16:42.030701 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 16:16:42.030713 kernel: Key type asymmetric registered Sep 4 16:16:42.030735 kernel: Asymmetric key parser 'x509' registered Sep 4 16:16:42.030747 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 16:16:42.030760 kernel: io scheduler mq-deadline registered Sep 4 16:16:42.030777 kernel: io scheduler kyber registered Sep 4 16:16:42.030789 kernel: io scheduler bfq registered Sep 4 16:16:42.030801 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 4 16:16:42.030813 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 4 16:16:42.030825 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 4 16:16:42.030838 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 4 16:16:42.030850 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 16:16:42.030863 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 4 16:16:42.030899 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 4 16:16:42.030911 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 4 16:16:42.030923 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 4 16:16:42.031170 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 4 16:16:42.031190 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 4 16:16:42.031398 kernel: rtc_cmos 00:04: registered as rtc0 Sep 4 16:16:42.031614 kernel: rtc_cmos 00:04: setting system clock to 2025-09-04T16:16:41 UTC (1757002601) Sep 4 16:16:42.031834 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Sep 4 16:16:42.031853 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 4 16:16:42.031866 kernel: efifb: probing for efifb Sep 4 16:16:42.031896 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Sep 4 16:16:42.031909 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Sep 4 16:16:42.031921 kernel: efifb: scrolling: redraw Sep 4 16:16:42.031939 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 4 16:16:42.031952 kernel: Console: switching to colour frame buffer device 160x50 Sep 4 16:16:42.031964 kernel: fb0: EFI VGA frame buffer device Sep 4 16:16:42.031977 kernel: pstore: Using crash dump compression: deflate Sep 4 16:16:42.031990 kernel: pstore: Registered efi_pstore as persistent store backend Sep 4 16:16:42.032002 kernel: NET: Registered PF_INET6 protocol family Sep 4 16:16:42.032014 kernel: Segment Routing with IPv6 Sep 4 16:16:42.032030 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 16:16:42.032043 kernel: NET: Registered PF_PACKET protocol family Sep 4 16:16:42.032056 kernel: Key type dns_resolver registered Sep 4 16:16:42.032068 kernel: IPI shorthand broadcast: enabled Sep 4 16:16:42.032081 kernel: sched_clock: Marking stable (3583002901, 158822506)->(3781149124, -39323717) Sep 4 16:16:42.032093 kernel: registered taskstats version 1 Sep 4 16:16:42.032106 kernel: Loading compiled-in X.509 certificates Sep 4 16:16:42.032123 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.44-flatcar: 250d2bafae7fa56c92cf187a0b8b7b2cdd349fc7' Sep 4 16:16:42.032136 kernel: Demotion targets for Node 0: null Sep 4 16:16:42.032148 kernel: Key type .fscrypt registered Sep 4 16:16:42.032161 kernel: Key type fscrypt-provisioning registered Sep 4 16:16:42.032174 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 16:16:42.032187 kernel: ima: Allocated hash algorithm: sha1 Sep 4 16:16:42.032200 kernel: ima: No architecture policies found Sep 4 16:16:42.032217 kernel: clk: Disabling unused clocks Sep 4 16:16:42.032229 kernel: Warning: unable to open an initial console. Sep 4 16:16:42.032243 kernel: Freeing unused kernel image (initmem) memory: 54288K Sep 4 16:16:42.032256 kernel: Write protecting the kernel read-only data: 24576k Sep 4 16:16:42.032269 kernel: Freeing unused kernel image (rodata/data gap) memory: 252K Sep 4 16:16:42.032282 kernel: Run /init as init process Sep 4 16:16:42.032295 kernel: with arguments: Sep 4 16:16:42.032311 kernel: /init Sep 4 16:16:42.032324 kernel: with environment: Sep 4 16:16:42.032337 kernel: HOME=/ Sep 4 16:16:42.032349 kernel: TERM=linux Sep 4 16:16:42.032362 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 16:16:42.032382 systemd[1]: Successfully made /usr/ read-only. Sep 4 16:16:42.032400 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 4 16:16:42.032419 systemd[1]: Detected virtualization kvm. Sep 4 16:16:42.032432 systemd[1]: Detected architecture x86-64. Sep 4 16:16:42.032445 systemd[1]: Running in initrd. Sep 4 16:16:42.032459 systemd[1]: No hostname configured, using default hostname. Sep 4 16:16:42.032472 systemd[1]: Hostname set to . Sep 4 16:16:42.032487 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Sep 4 16:16:42.032504 systemd[1]: Queued start job for default target initrd.target. Sep 4 16:16:42.032518 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 16:16:42.032532 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 16:16:42.032547 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 16:16:42.032561 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 16:16:42.032575 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 16:16:42.032594 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 16:16:42.032610 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 16:16:42.032624 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 16:16:42.032638 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 16:16:42.032656 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 16:16:42.032670 systemd[1]: Reached target paths.target - Path Units. Sep 4 16:16:42.032686 systemd[1]: Reached target slices.target - Slice Units. Sep 4 16:16:42.032701 systemd[1]: Reached target swap.target - Swaps. Sep 4 16:16:42.032714 systemd[1]: Reached target timers.target - Timer Units. Sep 4 16:16:42.032738 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 16:16:42.032752 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 16:16:42.032767 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 16:16:42.032781 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 4 16:16:42.032800 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 16:16:42.032814 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 16:16:42.032828 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 16:16:42.032842 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 16:16:42.032857 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 16:16:42.032872 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 16:16:42.032903 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 16:16:42.032922 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 4 16:16:42.032936 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 16:16:42.032951 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 16:16:42.032965 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 16:16:42.032980 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 16:16:42.032994 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 16:16:42.033012 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 16:16:42.033026 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 16:16:42.033039 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 16:16:42.033055 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 16:16:42.033074 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 16:16:42.033088 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:42.033140 systemd-journald[220]: Collecting audit messages is disabled. Sep 4 16:16:42.033175 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 4 16:16:42.033190 kernel: Bridge firewalling registered Sep 4 16:16:42.033204 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 16:16:42.033219 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 16:16:42.033234 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 16:16:42.033248 systemd-journald[220]: Journal started Sep 4 16:16:42.033283 systemd-journald[220]: Runtime Journal (/run/log/journal/1869873046204409b1b75752c7fa0111) is 6M, max 48.5M, 42.4M free. Sep 4 16:16:41.987913 systemd-modules-load[222]: Inserted module 'overlay' Sep 4 16:16:42.020604 systemd-modules-load[222]: Inserted module 'br_netfilter' Sep 4 16:16:42.037118 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 16:16:42.037141 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 16:16:42.043427 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 16:16:42.048302 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 16:16:42.051581 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 16:16:42.054929 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 16:16:42.057200 systemd-tmpfiles[247]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 4 16:16:42.068507 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 16:16:42.070689 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 16:16:42.090499 dracut-cmdline[261]: 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=39929ed91cc8dec12f10b74359379a21a9960032f4b779521fabb4147461485b Sep 4 16:16:42.107333 systemd-resolved[258]: Positive Trust Anchors: Sep 4 16:16:42.107356 systemd-resolved[258]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 16:16:42.107363 systemd-resolved[258]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Sep 4 16:16:42.107397 systemd-resolved[258]: 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 4 16:16:42.110181 systemd-resolved[258]: Defaulting to hostname 'linux'. Sep 4 16:16:42.111628 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 16:16:42.117321 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 16:16:42.205908 kernel: SCSI subsystem initialized Sep 4 16:16:42.214899 kernel: Loading iSCSI transport class v2.0-870. Sep 4 16:16:42.224901 kernel: iscsi: registered transport (tcp) Sep 4 16:16:42.245905 kernel: iscsi: registered transport (qla4xxx) Sep 4 16:16:42.245932 kernel: QLogic iSCSI HBA Driver Sep 4 16:16:42.266399 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 16:16:42.294290 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 16:16:42.297949 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 16:16:42.352509 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 16:16:42.356004 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 16:16:42.411904 kernel: raid6: avx2x4 gen() 30451 MB/s Sep 4 16:16:42.428895 kernel: raid6: avx2x2 gen() 31589 MB/s Sep 4 16:16:42.445912 kernel: raid6: avx2x1 gen() 26094 MB/s Sep 4 16:16:42.445929 kernel: raid6: using algorithm avx2x2 gen() 31589 MB/s Sep 4 16:16:42.464163 kernel: raid6: .... xor() 18791 MB/s, rmw enabled Sep 4 16:16:42.464209 kernel: raid6: using avx2x2 recovery algorithm Sep 4 16:16:42.490037 kernel: xor: automatically using best checksumming function avx Sep 4 16:16:42.659948 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 16:16:42.669642 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 16:16:42.673540 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 16:16:42.705973 systemd-udevd[474]: Using default interface naming scheme 'v257'. Sep 4 16:16:42.712046 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 16:16:42.715767 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 16:16:42.746030 dracut-pre-trigger[484]: rd.md=0: removing MD RAID activation Sep 4 16:16:42.776292 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 16:16:42.779897 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 16:16:42.866769 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 16:16:42.871559 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 16:16:42.908920 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 4 16:16:42.911470 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 4 16:16:42.918868 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 16:16:42.918911 kernel: cryptd: max_cpu_qlen set to 1000 Sep 4 16:16:42.933898 kernel: AES CTR mode by8 optimization enabled Sep 4 16:16:42.935916 kernel: libata version 3.00 loaded. Sep 4 16:16:42.944326 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 16:16:42.944464 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:42.945391 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 16:16:42.947274 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 16:16:42.954904 kernel: ahci 0000:00:1f.2: version 3.0 Sep 4 16:16:42.955163 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 4 16:16:42.957529 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 4 16:16:42.957770 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 4 16:16:42.957987 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 4 16:16:42.965345 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 4 16:16:42.974915 kernel: scsi host0: ahci Sep 4 16:16:42.983936 kernel: scsi host1: ahci Sep 4 16:16:42.993518 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 16:16:42.993656 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:42.999553 kernel: scsi host2: ahci Sep 4 16:16:42.999829 kernel: scsi host3: ahci Sep 4 16:16:43.000094 kernel: scsi host4: ahci Sep 4 16:16:43.000961 kernel: scsi host5: ahci Sep 4 16:16:43.001180 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 lpm-pol 1 Sep 4 16:16:43.002730 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 lpm-pol 1 Sep 4 16:16:43.002753 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 lpm-pol 1 Sep 4 16:16:43.004512 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 lpm-pol 1 Sep 4 16:16:43.004535 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 lpm-pol 1 Sep 4 16:16:43.006301 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 lpm-pol 1 Sep 4 16:16:43.011767 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 4 16:16:43.014914 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 4 16:16:43.031586 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 4 16:16:43.042144 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 4 16:16:43.052869 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 16:16:43.056019 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 16:16:43.058965 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 16:16:43.078902 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 16:16:43.083953 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 16:16:43.086081 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:43.316154 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 4 16:16:43.316222 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 4 16:16:43.316235 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 4 16:16:43.317919 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 4 16:16:43.317983 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 4 16:16:43.318910 kernel: ata3.00: LPM support broken, forcing max_power Sep 4 16:16:43.320248 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 4 16:16:43.320297 kernel: ata3.00: applying bridge limits Sep 4 16:16:43.320911 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 4 16:16:43.321927 kernel: ata3.00: LPM support broken, forcing max_power Sep 4 16:16:43.321954 kernel: ata3.00: configured for UDMA/100 Sep 4 16:16:43.322913 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 4 16:16:43.386454 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 4 16:16:43.386765 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 16:16:43.406912 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 4 16:16:43.733293 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 16:16:43.734223 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 16:16:43.735612 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 16:16:43.737843 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 16:16:43.740891 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 16:16:43.774644 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 16:16:44.089914 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 16:16:44.092328 disk-uuid[641]: The operation has completed successfully. Sep 4 16:16:44.123732 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 16:16:44.123863 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 16:16:44.156277 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 16:16:44.186643 sh[671]: Success Sep 4 16:16:44.205116 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 16:16:44.205165 kernel: device-mapper: uevent: version 1.0.3 Sep 4 16:16:44.206159 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 4 16:16:44.215934 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 4 16:16:44.245242 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 16:16:44.249532 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 16:16:44.264898 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 16:16:44.272735 kernel: BTRFS: device fsid ac7b5b49-8d71-4968-afd7-5e4410595bf4 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (683) Sep 4 16:16:44.272760 kernel: BTRFS info (device dm-0): first mount of filesystem ac7b5b49-8d71-4968-afd7-5e4410595bf4 Sep 4 16:16:44.272773 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 4 16:16:44.278900 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 16:16:44.278922 kernel: BTRFS info (device dm-0): enabling free space tree Sep 4 16:16:44.279691 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 16:16:44.280577 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 4 16:16:44.281676 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 16:16:44.285340 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 16:16:44.287797 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 16:16:44.311911 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (714) Sep 4 16:16:44.314086 kernel: BTRFS info (device vda6): first mount of filesystem c498a12e-1387-4e64-bf04-402560df6433 Sep 4 16:16:44.314121 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 16:16:44.317283 kernel: BTRFS info (device vda6): turning on async discard Sep 4 16:16:44.317308 kernel: BTRFS info (device vda6): enabling free space tree Sep 4 16:16:44.323239 kernel: BTRFS info (device vda6): last unmount of filesystem c498a12e-1387-4e64-bf04-402560df6433 Sep 4 16:16:44.323872 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 16:16:44.325622 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 16:16:44.504070 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 16:16:44.507478 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 16:16:44.539174 ignition[755]: Ignition 2.22.0 Sep 4 16:16:44.539775 ignition[755]: Stage: fetch-offline Sep 4 16:16:44.539848 ignition[755]: no configs at "/usr/lib/ignition/base.d" Sep 4 16:16:44.539859 ignition[755]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 16:16:44.539980 ignition[755]: parsed url from cmdline: "" Sep 4 16:16:44.539984 ignition[755]: no config URL provided Sep 4 16:16:44.539989 ignition[755]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 16:16:44.539998 ignition[755]: no config at "/usr/lib/ignition/user.ign" Sep 4 16:16:44.540025 ignition[755]: op(1): [started] loading QEMU firmware config module Sep 4 16:16:44.540030 ignition[755]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 4 16:16:44.550674 ignition[755]: op(1): [finished] loading QEMU firmware config module Sep 4 16:16:44.551949 ignition[755]: parsing config with SHA512: 9a796013463452c850eb680749f0f535093517a398fde0a9218570df3f5dadb6349cc31a51eecf395dacc33fad3f5af3ef0210fcefa19b7611942c40e7b1c63c Sep 4 16:16:44.559373 systemd-networkd[860]: lo: Link UP Sep 4 16:16:44.559385 systemd-networkd[860]: lo: Gained carrier Sep 4 16:16:44.561111 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 16:16:44.561756 systemd[1]: Reached target network.target - Network. Sep 4 16:16:44.562603 ignition[755]: fetch-offline: fetch-offline passed Sep 4 16:16:44.562151 systemd-networkd[860]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 4 16:16:44.562723 ignition[755]: Ignition finished successfully Sep 4 16:16:44.562156 systemd-networkd[860]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 16:16:44.562368 unknown[755]: fetched base config from "system" Sep 4 16:16:44.562376 unknown[755]: fetched user config from "qemu" Sep 4 16:16:44.563108 systemd-networkd[860]: eth0: Link UP Sep 4 16:16:44.564217 systemd-networkd[860]: eth0: Gained carrier Sep 4 16:16:44.564226 systemd-networkd[860]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 4 16:16:44.569735 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 16:16:44.572848 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 4 16:16:44.573930 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 16:16:44.585956 systemd-networkd[860]: eth0: DHCPv4 address 10.0.0.15/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 16:16:44.615439 ignition[868]: Ignition 2.22.0 Sep 4 16:16:44.615454 ignition[868]: Stage: kargs Sep 4 16:16:44.615587 ignition[868]: no configs at "/usr/lib/ignition/base.d" Sep 4 16:16:44.615598 ignition[868]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 16:16:44.616127 ignition[868]: kargs: kargs passed Sep 4 16:16:44.616175 ignition[868]: Ignition finished successfully Sep 4 16:16:44.620900 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 16:16:44.623953 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 16:16:44.666554 ignition[878]: Ignition 2.22.0 Sep 4 16:16:44.666568 ignition[878]: Stage: disks Sep 4 16:16:44.666754 ignition[878]: no configs at "/usr/lib/ignition/base.d" Sep 4 16:16:44.666766 ignition[878]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 16:16:44.667543 ignition[878]: disks: disks passed Sep 4 16:16:44.667590 ignition[878]: Ignition finished successfully Sep 4 16:16:44.674455 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 16:16:44.676704 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 16:16:44.677150 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 16:16:44.677527 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 16:16:44.677888 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 16:16:44.678351 systemd[1]: Reached target basic.target - Basic System. Sep 4 16:16:44.679738 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 16:16:44.712034 systemd-fsck[888]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 4 16:16:44.720098 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 16:16:44.723300 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 16:16:44.832904 kernel: EXT4-fs (vda9): mounted filesystem 5b9a7850-c07f-470b-a91c-362c3904243c r/w with ordered data mode. Quota mode: none. Sep 4 16:16:44.833126 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 16:16:44.834031 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 16:16:44.836970 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 16:16:44.839336 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 16:16:44.840381 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 16:16:44.840414 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 16:16:44.840436 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 16:16:44.859268 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 16:16:44.861163 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 16:16:44.866153 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (897) Sep 4 16:16:44.866176 kernel: BTRFS info (device vda6): first mount of filesystem c498a12e-1387-4e64-bf04-402560df6433 Sep 4 16:16:44.866195 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 16:16:44.868899 kernel: BTRFS info (device vda6): turning on async discard Sep 4 16:16:44.868922 kernel: BTRFS info (device vda6): enabling free space tree Sep 4 16:16:44.870738 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 16:16:44.915998 initrd-setup-root[921]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 16:16:44.920952 initrd-setup-root[928]: cut: /sysroot/etc/group: No such file or directory Sep 4 16:16:44.924848 initrd-setup-root[935]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 16:16:44.929502 initrd-setup-root[942]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 16:16:45.021722 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 16:16:45.023248 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 16:16:45.025012 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 16:16:45.054922 kernel: BTRFS info (device vda6): last unmount of filesystem c498a12e-1387-4e64-bf04-402560df6433 Sep 4 16:16:45.068039 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 16:16:45.086077 ignition[1011]: INFO : Ignition 2.22.0 Sep 4 16:16:45.086077 ignition[1011]: INFO : Stage: mount Sep 4 16:16:45.087771 ignition[1011]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 16:16:45.087771 ignition[1011]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 16:16:45.089938 ignition[1011]: INFO : mount: mount passed Sep 4 16:16:45.090646 ignition[1011]: INFO : Ignition finished successfully Sep 4 16:16:45.094151 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 16:16:45.096086 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 16:16:45.271477 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 16:16:45.273199 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 16:16:45.298920 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1022) Sep 4 16:16:45.300902 kernel: BTRFS info (device vda6): first mount of filesystem c498a12e-1387-4e64-bf04-402560df6433 Sep 4 16:16:45.300961 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 16:16:45.303898 kernel: BTRFS info (device vda6): turning on async discard Sep 4 16:16:45.303916 kernel: BTRFS info (device vda6): enabling free space tree Sep 4 16:16:45.305530 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 16:16:45.387395 ignition[1039]: INFO : Ignition 2.22.0 Sep 4 16:16:45.387395 ignition[1039]: INFO : Stage: files Sep 4 16:16:45.389311 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 16:16:45.389311 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 16:16:45.389311 ignition[1039]: DEBUG : files: compiled without relabeling support, skipping Sep 4 16:16:45.392724 ignition[1039]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 16:16:45.392724 ignition[1039]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 16:16:45.392724 ignition[1039]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 16:16:45.392724 ignition[1039]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 16:16:45.392724 ignition[1039]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 16:16:45.392424 unknown[1039]: wrote ssh authorized keys file for user: core Sep 4 16:16:45.401129 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 16:16:45.401129 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 16:16:45.401129 ignition[1039]: INFO : files: op(4): [started] processing unit "docker.service" Sep 4 16:16:45.401129 ignition[1039]: INFO : files: op(4): [finished] processing unit "docker.service" Sep 4 16:16:45.401129 ignition[1039]: INFO : files: op(5): [started] processing unit "coreos-metadata.service" Sep 4 16:16:45.401129 ignition[1039]: INFO : files: op(5): op(6): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 16:16:45.410751 ignition[1039]: INFO : files: op(5): op(6): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 16:16:45.410751 ignition[1039]: INFO : files: op(5): [finished] processing unit "coreos-metadata.service" Sep 4 16:16:45.410751 ignition[1039]: INFO : files: op(7): [started] setting preset to disabled for "coreos-metadata.service" Sep 4 16:16:45.426030 ignition[1039]: INFO : files: op(7): op(8): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 16:16:45.430676 ignition[1039]: INFO : files: op(7): op(8): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 16:16:45.432194 ignition[1039]: INFO : files: op(7): [finished] setting preset to disabled for "coreos-metadata.service" Sep 4 16:16:45.432194 ignition[1039]: INFO : files: op(9): [started] setting preset to enabled for "docker.service" Sep 4 16:16:45.432194 ignition[1039]: INFO : files: op(9): [finished] setting preset to enabled for "docker.service" Sep 4 16:16:45.432194 ignition[1039]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 16:16:45.432194 ignition[1039]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 16:16:45.432194 ignition[1039]: INFO : files: files passed Sep 4 16:16:45.432194 ignition[1039]: INFO : Ignition finished successfully Sep 4 16:16:45.434366 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 16:16:45.436996 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 16:16:45.440212 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 16:16:45.467532 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 16:16:45.467674 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 16:16:45.471704 initrd-setup-root-after-ignition[1068]: grep: /sysroot/oem/oem-release: No such file or directory Sep 4 16:16:45.476100 initrd-setup-root-after-ignition[1070]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 16:16:45.476100 initrd-setup-root-after-ignition[1070]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 16:16:45.479486 initrd-setup-root-after-ignition[1074]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 16:16:45.478529 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 16:16:45.480432 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 16:16:45.483425 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 16:16:45.530342 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 16:16:45.530493 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 16:16:45.531384 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 16:16:45.533799 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 16:16:45.534327 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 16:16:45.535339 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 16:16:45.577006 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 16:16:45.579927 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 16:16:45.615788 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 16:16:45.616359 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 16:16:45.616706 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 16:16:45.617179 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 16:16:45.617313 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 16:16:45.622342 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 16:16:45.622665 systemd[1]: Stopped target basic.target - Basic System. Sep 4 16:16:45.623004 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 16:16:45.623301 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 16:16:45.623630 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 16:16:45.623992 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 4 16:16:45.624429 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 16:16:45.624752 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 16:16:45.625248 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 16:16:45.625556 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 16:16:45.625891 systemd[1]: Stopped target swap.target - Swaps. Sep 4 16:16:45.643315 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 16:16:45.643510 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 16:16:45.645856 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 16:16:45.646500 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 16:16:45.646766 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 16:16:45.650762 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 16:16:45.651377 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 16:16:45.651529 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 16:16:45.655997 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 16:16:45.656156 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 16:16:45.657765 systemd[1]: Stopped target paths.target - Path Units. Sep 4 16:16:45.660475 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 16:16:45.664954 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 16:16:45.665340 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 16:16:45.668173 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 16:16:45.668475 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 16:16:45.668583 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 16:16:45.671236 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 16:16:45.671327 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 16:16:45.672861 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 16:16:45.673005 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 16:16:45.674590 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 16:16:45.674709 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 16:16:45.677631 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 16:16:45.680751 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 16:16:45.680867 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 16:16:45.682089 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 16:16:45.684102 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 16:16:45.684246 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 16:16:45.686725 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 16:16:45.686831 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 16:16:45.689346 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 16:16:45.689454 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 16:16:45.697449 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 16:16:45.700167 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 16:16:45.724767 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 16:16:45.731207 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 16:16:45.731817 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 16:16:45.734144 ignition[1094]: INFO : Ignition 2.22.0 Sep 4 16:16:45.734144 ignition[1094]: INFO : Stage: umount Sep 4 16:16:45.734144 ignition[1094]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 16:16:45.734144 ignition[1094]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 16:16:45.734144 ignition[1094]: INFO : umount: umount passed Sep 4 16:16:45.734144 ignition[1094]: INFO : Ignition finished successfully Sep 4 16:16:45.736449 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 16:16:45.736572 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 16:16:45.737933 systemd[1]: Stopped target network.target - Network. Sep 4 16:16:45.739669 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 16:16:45.739730 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 16:16:45.741399 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 16:16:45.741450 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 16:16:45.742716 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 16:16:45.742772 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 16:16:45.743352 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 16:16:45.743407 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 16:16:45.743684 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 16:16:45.743736 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 16:16:45.744235 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 16:16:45.749622 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 16:16:45.758668 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 16:16:45.758846 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 16:16:45.773911 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 16:16:45.774092 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 16:16:45.778841 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 4 16:16:45.780970 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 16:16:45.781045 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 16:16:45.782288 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 16:16:45.785710 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 16:16:45.786745 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 16:16:45.788169 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 16:16:45.788234 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 16:16:45.788578 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 16:16:45.788634 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 16:16:45.788979 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 16:16:45.814308 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 16:16:45.814680 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 16:16:45.815955 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 16:16:45.816100 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 16:16:45.819208 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 16:16:45.819313 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 16:16:45.820295 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 16:16:45.820341 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 16:16:45.820577 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 16:16:45.820666 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 16:16:45.821828 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 16:16:45.821946 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 16:16:45.822919 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 16:16:45.822985 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 16:16:45.824680 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 16:16:45.832784 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 4 16:16:45.832900 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 16:16:45.835852 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 16:16:45.836010 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 16:16:45.836719 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 16:16:45.836772 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:45.865326 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 16:16:45.865460 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 16:16:45.866162 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 16:16:45.870618 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 16:16:45.882454 systemd[1]: Switching root. Sep 4 16:16:45.931153 systemd-journald[220]: Journal stopped Sep 4 16:16:46.856870 systemd-journald[220]: Received SIGTERM from PID 1 (systemd). Sep 4 16:16:46.856981 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 16:16:46.856997 kernel: SELinux: policy capability open_perms=1 Sep 4 16:16:46.857009 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 16:16:46.857023 kernel: SELinux: policy capability always_check_network=0 Sep 4 16:16:46.857039 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 16:16:46.857056 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 16:16:46.857068 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 16:16:46.857080 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 16:16:46.857093 kernel: SELinux: policy capability userspace_initial_context=0 Sep 4 16:16:46.857105 kernel: audit: type=1403 audit(1757002606.040:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 16:16:46.857118 systemd[1]: Successfully loaded SELinux policy in 60.334ms. Sep 4 16:16:46.857139 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.364ms. Sep 4 16:16:46.857156 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 4 16:16:46.857169 systemd[1]: Detected virtualization kvm. Sep 4 16:16:46.857182 systemd[1]: Detected architecture x86-64. Sep 4 16:16:46.857195 systemd[1]: Detected first boot. Sep 4 16:16:46.857208 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Sep 4 16:16:46.857221 zram_generator::config[1139]: No configuration found. Sep 4 16:16:46.857240 kernel: Guest personality initialized and is inactive Sep 4 16:16:46.857255 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 4 16:16:46.857283 kernel: Initialized host personality Sep 4 16:16:46.857298 kernel: NET: Registered PF_VSOCK protocol family Sep 4 16:16:46.857310 systemd[1]: Populated /etc with preset unit settings. Sep 4 16:16:46.857323 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 16:16:46.857336 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 16:16:46.857349 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 16:16:46.857379 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 16:16:46.857395 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 16:16:46.857410 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 16:16:46.857423 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 16:16:46.857441 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 16:16:46.857459 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 16:16:46.857476 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 16:16:46.857490 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 16:16:46.857502 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 16:16:46.857515 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 16:16:46.857529 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 16:16:46.857544 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 16:16:46.857557 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 16:16:46.857571 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 16:16:46.857593 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 16:16:46.857606 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 16:16:46.857619 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 16:16:46.857634 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 16:16:46.857648 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 16:16:46.857661 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 16:16:46.857673 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 16:16:46.857696 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 16:16:46.857709 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 16:16:46.857723 systemd[1]: Reached target slices.target - Slice Units. Sep 4 16:16:46.857735 systemd[1]: Reached target swap.target - Swaps. Sep 4 16:16:46.857750 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 16:16:46.857764 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 16:16:46.857789 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 4 16:16:46.857807 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 16:16:46.857824 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 16:16:46.857837 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 16:16:46.857850 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 16:16:46.857867 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 16:16:46.857895 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 16:16:46.857909 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 16:16:46.857923 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:46.857936 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 16:16:46.857949 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 16:16:46.857962 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 16:16:46.857978 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 4 16:16:46.857991 systemd[1]: Reached target machines.target - Containers. Sep 4 16:16:46.858004 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 16:16:46.858027 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 16:16:46.858041 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 16:16:46.858054 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 16:16:46.858070 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 16:16:46.858083 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 16:16:46.858096 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 16:16:46.858108 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 16:16:46.858122 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 16:16:46.858135 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 16:16:46.858148 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 16:16:46.858163 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 16:16:46.858176 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 16:16:46.858189 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 16:16:46.858203 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 4 16:16:46.858216 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 16:16:46.858231 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 16:16:46.858245 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 16:16:46.858258 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 16:16:46.858272 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 4 16:16:46.858285 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 16:16:46.858305 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 16:16:46.858332 kernel: loop: module loaded Sep 4 16:16:46.858345 systemd[1]: Stopped verity-setup.service. Sep 4 16:16:46.858426 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:46.858443 kernel: fuse: init (API version 7.41) Sep 4 16:16:46.858599 systemd-journald[1210]: Collecting audit messages is disabled. Sep 4 16:16:46.858797 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 16:16:46.858981 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 16:16:46.859002 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 16:16:46.859984 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 16:16:46.860002 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 16:16:46.860016 systemd-journald[1210]: Journal started Sep 4 16:16:46.860040 systemd-journald[1210]: Runtime Journal (/run/log/journal/1869873046204409b1b75752c7fa0111) is 6M, max 48.5M, 42.4M free. Sep 4 16:16:46.607415 systemd[1]: Queued start job for default target multi-user.target. Sep 4 16:16:46.625017 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 4 16:16:46.625564 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 16:16:46.864922 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 16:16:46.864449 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 16:16:46.865750 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 16:16:46.867244 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 16:16:46.871903 kernel: ACPI: bus type drm_connector registered Sep 4 16:16:46.875182 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 16:16:46.876933 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 16:16:46.877155 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 16:16:46.878689 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 16:16:46.880176 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 16:16:46.880393 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 16:16:46.881768 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 16:16:46.882003 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 16:16:46.883455 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 16:16:46.883680 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 16:16:46.885140 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 16:16:46.885358 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 16:16:46.886756 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 16:16:46.888277 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 16:16:46.890525 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 16:16:46.892337 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 4 16:16:46.907819 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 16:16:46.909343 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Sep 4 16:16:46.910576 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 16:16:46.910610 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 16:16:46.912541 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 4 16:16:46.914080 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 16:16:46.915597 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 16:16:46.917590 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 16:16:46.918823 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 16:16:46.921012 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 16:16:46.922117 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 16:16:46.924154 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 16:16:46.937007 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 16:16:46.939960 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 16:16:46.944478 systemd-journald[1210]: Time spent on flushing to /var/log/journal/1869873046204409b1b75752c7fa0111 is 21.732ms for 1031 entries. Sep 4 16:16:46.944478 systemd-journald[1210]: System Journal (/var/log/journal/1869873046204409b1b75752c7fa0111) is 8M, max 195.6M, 187.6M free. Sep 4 16:16:46.975566 systemd-journald[1210]: Received client request to flush runtime journal. Sep 4 16:16:46.975671 kernel: loop0: detected capacity change from 0 to 128016 Sep 4 16:16:46.954307 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 16:16:46.955732 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 16:16:46.962207 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 4 16:16:46.972616 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 16:16:46.981230 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 16:16:46.983312 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 16:16:46.987533 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 16:16:46.998195 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 16:16:47.002312 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 16:16:47.005753 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 16:16:47.009994 kernel: loop1: detected capacity change from 0 to 111000 Sep 4 16:16:47.148843 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 4 16:16:47.156071 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 16:16:47.173325 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Sep 4 16:16:47.173344 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Sep 4 16:16:47.177940 kernel: loop2: detected capacity change from 0 to 128016 Sep 4 16:16:47.182866 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 16:16:47.188921 kernel: loop3: detected capacity change from 0 to 111000 Sep 4 16:16:47.197072 (sd-merge)[1277]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw'. Sep 4 16:16:47.202626 (sd-merge)[1277]: Merged extensions into '/usr'. Sep 4 16:16:47.207730 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 16:16:47.212556 systemd[1]: Starting ensure-sysext.service... Sep 4 16:16:47.217064 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 16:16:47.224009 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 16:16:47.239749 systemd[1]: Reload requested from client PID 1280 ('systemctl') (unit ensure-sysext.service)... Sep 4 16:16:47.241137 systemd[1]: Reloading... Sep 4 16:16:47.258643 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 4 16:16:47.258697 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 4 16:16:47.261851 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 16:16:47.262197 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 16:16:47.265134 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 16:16:47.265558 systemd-tmpfiles[1281]: ACLs are not supported, ignoring. Sep 4 16:16:47.265729 systemd-tmpfiles[1281]: ACLs are not supported, ignoring. Sep 4 16:16:47.273154 systemd-tmpfiles[1281]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 16:16:47.273250 systemd-tmpfiles[1281]: Skipping /boot Sep 4 16:16:47.285021 systemd-tmpfiles[1281]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 16:16:47.285219 systemd-tmpfiles[1281]: Skipping /boot Sep 4 16:16:47.330950 zram_generator::config[1319]: No configuration found. Sep 4 16:16:47.401163 systemd-resolved[1271]: Positive Trust Anchors: Sep 4 16:16:47.401189 systemd-resolved[1271]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 16:16:47.401194 systemd-resolved[1271]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Sep 4 16:16:47.401226 systemd-resolved[1271]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 4 16:16:47.407655 systemd-resolved[1271]: Defaulting to hostname 'linux'. Sep 4 16:16:47.537981 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 16:16:47.538313 systemd[1]: Reloading finished in 296 ms. Sep 4 16:16:47.573398 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 16:16:47.575371 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 16:16:47.603077 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 16:16:47.606244 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 16:16:47.608790 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 16:16:47.614144 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 16:16:47.622554 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 16:16:47.627163 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 16:16:47.633819 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:47.634150 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 16:16:47.635824 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 16:16:47.647334 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 16:16:47.651083 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 16:16:47.654955 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 16:16:47.660520 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 16:16:47.662067 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 16:16:47.662188 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 4 16:16:47.662294 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:47.665534 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 16:16:47.670897 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 16:16:47.677651 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 16:16:47.677975 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 16:16:47.685368 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 16:16:47.685629 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 16:16:47.687641 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 16:16:47.687928 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 16:16:47.689681 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 16:16:47.690084 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 16:16:47.702266 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:47.702651 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 16:16:47.706336 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 16:16:47.710226 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 16:16:47.720872 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 16:16:47.725953 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 16:16:47.735043 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 16:16:47.737475 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 16:16:47.739450 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 4 16:16:47.739584 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:47.742186 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 16:16:47.745224 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 16:16:47.748598 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 16:16:47.748844 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 16:16:47.751234 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 16:16:47.751491 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 16:16:47.753396 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 16:16:47.753640 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 16:16:47.755782 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 16:16:47.756860 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 16:16:47.757409 augenrules[1393]: No rules Sep 4 16:16:47.758259 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 16:16:47.758666 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 16:16:47.769266 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 16:16:47.769543 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 16:16:47.778203 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:47.779854 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 16:16:47.781297 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 16:16:47.782680 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 16:16:47.785987 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 16:16:47.789969 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 16:16:47.793123 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 16:16:47.795786 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 16:16:47.799117 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 16:16:47.800354 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 16:16:47.800472 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 4 16:16:47.800649 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 16:16:47.803428 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 16:16:47.803737 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 16:16:47.813141 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 16:16:47.813489 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 16:16:47.817808 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 16:16:47.818087 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 16:16:47.820219 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 16:16:47.820605 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 16:16:47.822546 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 16:16:47.823350 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 16:16:47.825437 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 16:16:47.825964 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 16:16:47.831174 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 16:16:47.833035 augenrules[1407]: /sbin/augenrules: No change Sep 4 16:16:47.835070 systemd[1]: Finished ensure-sysext.service. Sep 4 16:16:47.843168 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 16:16:47.847844 augenrules[1436]: No rules Sep 4 16:16:47.849281 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 16:16:47.849560 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 16:16:47.853544 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 16:16:47.856289 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 16:16:47.857692 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 16:16:47.857983 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 16:16:47.869040 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 4 16:16:47.872670 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 16:16:47.874075 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 4 16:16:47.875971 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 16:16:47.877340 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 16:16:47.911077 systemd-udevd[1448]: Using default interface naming scheme 'v257'. Sep 4 16:16:47.941163 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 16:16:47.947136 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 16:16:47.956494 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 4 16:16:47.958197 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 16:16:48.096977 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 16:16:48.119597 systemd-networkd[1457]: lo: Link UP Sep 4 16:16:48.119609 systemd-networkd[1457]: lo: Gained carrier Sep 4 16:16:48.127305 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 16:16:48.129539 systemd[1]: Reached target network.target - Network. Sep 4 16:16:48.133017 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 4 16:16:48.140086 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 16:16:48.141557 ldconfig[1356]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 16:16:48.154021 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 16:16:48.159049 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 16:16:48.169902 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 16:16:48.174902 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 16:16:48.179231 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 16:16:48.180855 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 16:16:48.184904 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 4 16:16:48.186736 systemd-networkd[1457]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 4 16:16:48.186750 systemd-networkd[1457]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 16:16:48.188131 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 16:16:48.188968 systemd-networkd[1457]: eth0: Link UP Sep 4 16:16:48.189691 systemd-networkd[1457]: eth0: Gained carrier Sep 4 16:16:48.189720 systemd-networkd[1457]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Sep 4 16:16:48.189818 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 16:16:48.191401 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 4 16:16:48.192666 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 4 16:16:48.194266 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 16:16:48.195731 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 16:16:48.197129 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 16:16:48.198594 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 16:16:48.198633 systemd[1]: Reached target paths.target - Path Units. Sep 4 16:16:48.199912 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 4 16:16:48.200896 systemd[1]: Reached target timers.target - Timer Units. Sep 4 16:16:48.200947 systemd-networkd[1457]: eth0: DHCPv4 address 10.0.0.15/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 16:16:48.202822 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 16:16:48.203135 systemd-timesyncd[1447]: Network configuration changed, trying to establish connection. Sep 4 16:16:48.205104 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 16:16:48.206512 kernel: ACPI: button: Power Button [PWRF] Sep 4 16:16:48.207475 systemd-timesyncd[1447]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 4 16:16:48.207529 systemd-timesyncd[1447]: Initial clock synchronization to Thu 2025-09-04 16:16:48.572504 UTC. Sep 4 16:16:48.209195 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 4 16:16:48.210836 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 4 16:16:48.212268 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 4 16:16:48.216439 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 16:16:48.218303 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 4 16:16:48.221085 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 16:16:48.222805 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 16:16:48.226072 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 4 16:16:48.227230 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 4 16:16:48.227460 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 4 16:16:48.232432 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 16:16:48.233469 systemd[1]: Reached target basic.target - Basic System. Sep 4 16:16:48.234476 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 16:16:48.234509 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 16:16:48.235713 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 16:16:48.237925 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 16:16:48.239913 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 16:16:48.246097 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 16:16:48.251152 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 16:16:48.252280 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 4 16:16:48.254394 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 4 16:16:48.258640 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 16:16:48.260155 jq[1513]: false Sep 4 16:16:48.262145 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 16:16:48.266132 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 16:16:48.276086 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 16:16:48.277185 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 16:16:48.277745 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 16:16:48.280155 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 16:16:48.284059 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 16:16:48.290323 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 16:16:48.291586 google_oslogin_nss_cache[1518]: oslogin_cache_refresh[1518]: Refreshing passwd entry cache Sep 4 16:16:48.291331 oslogin_cache_refresh[1518]: Refreshing passwd entry cache Sep 4 16:16:48.292060 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 16:16:48.292341 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 16:16:48.292688 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 16:16:48.292969 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 16:16:48.301951 google_oslogin_nss_cache[1518]: oslogin_cache_refresh[1518]: Failure getting users, quitting Sep 4 16:16:48.301951 google_oslogin_nss_cache[1518]: oslogin_cache_refresh[1518]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 4 16:16:48.301951 google_oslogin_nss_cache[1518]: oslogin_cache_refresh[1518]: Refreshing group entry cache Sep 4 16:16:48.300338 oslogin_cache_refresh[1518]: Failure getting users, quitting Sep 4 16:16:48.300364 oslogin_cache_refresh[1518]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 4 16:16:48.300442 oslogin_cache_refresh[1518]: Refreshing group entry cache Sep 4 16:16:48.307848 google_oslogin_nss_cache[1518]: oslogin_cache_refresh[1518]: Failure getting groups, quitting Sep 4 16:16:48.307848 google_oslogin_nss_cache[1518]: oslogin_cache_refresh[1518]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 4 16:16:48.306440 oslogin_cache_refresh[1518]: Failure getting groups, quitting Sep 4 16:16:48.306455 oslogin_cache_refresh[1518]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 4 16:16:48.312815 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 4 16:16:48.313170 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 4 16:16:48.327054 jq[1526]: true Sep 4 16:16:48.420005 extend-filesystems[1516]: Found /dev/vda6 Sep 4 16:16:48.557142 update_engine[1525]: I20250904 16:16:48.340618 1525 main.cc:92] Flatcar Update Engine starting Sep 4 16:16:48.557142 update_engine[1525]: I20250904 16:16:48.440232 1525 update_check_scheduler.cc:74] Next update check in 11m21s Sep 4 16:16:48.557418 sshd_keygen[1544]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 16:16:48.434286 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 16:16:48.433834 dbus-daemon[1510]: [system] SELinux support is enabled Sep 4 16:16:48.557853 extend-filesystems[1516]: Found /dev/vda9 Sep 4 16:16:48.558167 (ntainerd)[1556]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 16:16:48.564993 jq[1558]: true Sep 4 16:16:48.566413 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 4 16:16:48.566463 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 16:16:48.567780 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 4 16:16:48.567807 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 16:16:48.571324 systemd[1]: Started update-engine.service - Update Engine. Sep 4 16:16:48.581111 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 16:16:48.581518 extend-filesystems[1516]: Checking size of /dev/vda9 Sep 4 16:16:48.584204 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 16:16:48.584469 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 16:16:48.605699 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 16:16:48.608640 extend-filesystems[1516]: Old size kept for /dev/vda9 Sep 4 16:16:48.609630 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 16:16:48.609975 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 16:16:48.641125 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 16:16:48.648225 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 16:16:48.658385 kernel: kvm_amd: TSC scaling supported Sep 4 16:16:48.658465 kernel: kvm_amd: Nested Virtualization enabled Sep 4 16:16:48.658483 kernel: kvm_amd: Nested Paging enabled Sep 4 16:16:48.659185 kernel: kvm_amd: LBR virtualization supported Sep 4 16:16:48.661979 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 4 16:16:48.662031 kernel: kvm_amd: Virtual GIF supported Sep 4 16:16:48.677720 systemd-logind[1523]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 16:16:48.678798 systemd-logind[1523]: New seat seat0. Sep 4 16:16:48.681067 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 16:16:48.682995 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 16:16:48.687173 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:48.701212 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 16:16:48.805404 bash[1597]: Updated "/home/core/.ssh/authorized_keys" Sep 4 16:16:48.812122 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 16:16:48.814435 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 16:16:48.814742 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 16:16:48.822961 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 16:16:48.825143 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 16:16:48.828489 systemd-logind[1523]: Watching system buttons on /dev/input/event2 (Power Button) Sep 4 16:16:48.851928 kernel: EDAC MC: Ver: 3.0.0 Sep 4 16:16:48.868832 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 16:16:48.882241 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 16:16:48.884474 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 16:16:48.884943 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 16:16:48.890949 locksmithd[1570]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 16:16:48.920269 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 16:16:49.026749 containerd[1556]: time="2025-09-04T16:16:49Z" 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 4 16:16:49.027599 containerd[1556]: time="2025-09-04T16:16:49.027557551Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 4 16:16:49.039816 containerd[1556]: time="2025-09-04T16:16:49.039775328Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="12.153µs" Sep 4 16:16:49.039816 containerd[1556]: time="2025-09-04T16:16:49.039803613Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 4 16:16:49.039887 containerd[1556]: time="2025-09-04T16:16:49.039825120Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 4 16:16:49.040061 containerd[1556]: time="2025-09-04T16:16:49.040035008Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 4 16:16:49.040061 containerd[1556]: time="2025-09-04T16:16:49.040055174Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 4 16:16:49.040125 containerd[1556]: time="2025-09-04T16:16:49.040083523Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040182 containerd[1556]: time="2025-09-04T16:16:49.040155304Z" 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 4 16:16:49.040182 containerd[1556]: time="2025-09-04T16:16:49.040172307Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040445 containerd[1556]: time="2025-09-04T16:16:49.040418975Z" 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 4 16:16:49.040445 containerd[1556]: time="2025-09-04T16:16:49.040437895Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040487 containerd[1556]: time="2025-09-04T16:16:49.040449261Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040487 containerd[1556]: time="2025-09-04T16:16:49.040457579Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040601 containerd[1556]: time="2025-09-04T16:16:49.040578316Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040904 containerd[1556]: time="2025-09-04T16:16:49.040881775Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 4 16:16:49.040947 containerd[1556]: time="2025-09-04T16:16:49.040934343Z" 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 4 16:16:49.040979 containerd[1556]: time="2025-09-04T16:16:49.040946685Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 4 16:16:49.041000 containerd[1556]: time="2025-09-04T16:16:49.040985833Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 4 16:16:49.041337 containerd[1556]: time="2025-09-04T16:16:49.041309993Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 4 16:16:49.041457 containerd[1556]: time="2025-09-04T16:16:49.041442882Z" level=info msg="metadata content store policy set" policy=shared Sep 4 16:16:49.222508 containerd[1556]: time="2025-09-04T16:16:49.222426867Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 4 16:16:49.222648 containerd[1556]: time="2025-09-04T16:16:49.222595761Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 4 16:16:49.222648 containerd[1556]: time="2025-09-04T16:16:49.222629881Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 4 16:16:49.222719 containerd[1556]: time="2025-09-04T16:16:49.222657391Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 4 16:16:49.222719 containerd[1556]: time="2025-09-04T16:16:49.222671691Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 4 16:16:49.222719 containerd[1556]: time="2025-09-04T16:16:49.222681990Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 4 16:16:49.222719 containerd[1556]: time="2025-09-04T16:16:49.222703434Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 4 16:16:49.222807 containerd[1556]: time="2025-09-04T16:16:49.222723034Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 4 16:16:49.222807 containerd[1556]: time="2025-09-04T16:16:49.222765578Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 4 16:16:49.222807 containerd[1556]: time="2025-09-04T16:16:49.222782340Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 4 16:16:49.222807 containerd[1556]: time="2025-09-04T16:16:49.222791370Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223201119Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223483143Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223521162Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223639561Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223666747Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223708735Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223728293Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 4 16:16:49.223896 containerd[1556]: time="2025-09-04T16:16:49.223740561Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 4 16:16:49.224216 containerd[1556]: time="2025-09-04T16:16:49.223968718Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 4 16:16:49.224216 containerd[1556]: time="2025-09-04T16:16:49.224177339Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 4 16:16:49.224216 containerd[1556]: time="2025-09-04T16:16:49.224205728Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 4 16:16:49.224297 containerd[1556]: time="2025-09-04T16:16:49.224226555Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 4 16:16:49.224452 containerd[1556]: time="2025-09-04T16:16:49.224400981Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 4 16:16:49.224452 containerd[1556]: time="2025-09-04T16:16:49.224439867Z" level=info msg="Start snapshots syncer" Sep 4 16:16:49.224630 containerd[1556]: time="2025-09-04T16:16:49.224551689Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 4 16:16:49.225136 containerd[1556]: time="2025-09-04T16:16:49.225072169Z" 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 4 16:16:49.225398 containerd[1556]: time="2025-09-04T16:16:49.225140022Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 4 16:16:49.225398 containerd[1556]: time="2025-09-04T16:16:49.225234977Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 4 16:16:49.225492 containerd[1556]: time="2025-09-04T16:16:49.225464916Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 4 16:16:49.225518 containerd[1556]: time="2025-09-04T16:16:49.225494280Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 4 16:16:49.225518 containerd[1556]: time="2025-09-04T16:16:49.225505929Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 4 16:16:49.225518 containerd[1556]: time="2025-09-04T16:16:49.225516416Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 4 16:16:49.225584 containerd[1556]: time="2025-09-04T16:16:49.225533680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 4 16:16:49.225584 containerd[1556]: time="2025-09-04T16:16:49.225550746Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 4 16:16:49.225584 containerd[1556]: time="2025-09-04T16:16:49.225564082Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 4 16:16:49.225652 containerd[1556]: time="2025-09-04T16:16:49.225592817Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 4 16:16:49.225652 containerd[1556]: time="2025-09-04T16:16:49.225608594Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 4 16:16:49.225652 containerd[1556]: time="2025-09-04T16:16:49.225635339Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 4 16:16:49.225719 containerd[1556]: time="2025-09-04T16:16:49.225684179Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 4 16:16:49.225719 containerd[1556]: time="2025-09-04T16:16:49.225704942Z" 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 4 16:16:49.225719 containerd[1556]: time="2025-09-04T16:16:49.225715220Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 16:16:49.225791 containerd[1556]: time="2025-09-04T16:16:49.225725507Z" 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 4 16:16:49.225791 containerd[1556]: time="2025-09-04T16:16:49.225734422Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 4 16:16:49.225791 containerd[1556]: time="2025-09-04T16:16:49.225744049Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 4 16:16:49.225791 containerd[1556]: time="2025-09-04T16:16:49.225756097Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 4 16:16:49.225791 containerd[1556]: time="2025-09-04T16:16:49.225781795Z" level=info msg="runtime interface created" Sep 4 16:16:49.225791 containerd[1556]: time="2025-09-04T16:16:49.225787284Z" level=info msg="created NRI interface" Sep 4 16:16:49.225928 containerd[1556]: time="2025-09-04T16:16:49.225795895Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 4 16:16:49.225928 containerd[1556]: time="2025-09-04T16:16:49.225807723Z" level=info msg="Connect containerd service" Sep 4 16:16:49.225928 containerd[1556]: time="2025-09-04T16:16:49.225914379Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 16:16:49.227159 containerd[1556]: time="2025-09-04T16:16:49.227110344Z" 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 4 16:16:49.428441 containerd[1556]: time="2025-09-04T16:16:49.428297100Z" level=info msg="Start subscribing containerd event" Sep 4 16:16:49.428441 containerd[1556]: time="2025-09-04T16:16:49.428362848Z" level=info msg="Start recovering state" Sep 4 16:16:49.428567 containerd[1556]: time="2025-09-04T16:16:49.428520072Z" level=info msg="Start event monitor" Sep 4 16:16:49.428567 containerd[1556]: time="2025-09-04T16:16:49.428536163Z" level=info msg="Start cni network conf syncer for default" Sep 4 16:16:49.428567 containerd[1556]: time="2025-09-04T16:16:49.428544774Z" level=info msg="Start streaming server" Sep 4 16:16:49.428567 containerd[1556]: time="2025-09-04T16:16:49.428564700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 4 16:16:49.428672 containerd[1556]: time="2025-09-04T16:16:49.428577973Z" level=info msg="runtime interface starting up..." Sep 4 16:16:49.428672 containerd[1556]: time="2025-09-04T16:16:49.428589989Z" level=info msg="starting plugins..." Sep 4 16:16:49.428672 containerd[1556]: time="2025-09-04T16:16:49.428624267Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 4 16:16:49.428738 containerd[1556]: time="2025-09-04T16:16:49.428667030Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 16:16:49.428761 containerd[1556]: time="2025-09-04T16:16:49.428743787Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 16:16:49.429017 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 16:16:49.429431 containerd[1556]: time="2025-09-04T16:16:49.429402521Z" level=info msg="containerd successfully booted in 0.403307s" Sep 4 16:16:49.959072 systemd-networkd[1457]: eth0: Gained IPv6LL Sep 4 16:16:49.962784 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 16:16:49.964756 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 16:16:49.967539 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 16:16:49.969892 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 16:16:50.018260 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 16:16:50.020277 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 16:16:50.020578 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 16:16:50.023000 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 16:16:50.023277 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 16:16:50.024712 systemd[1]: Startup finished in 3.648s (kernel) + 4.396s (initrd) + 4.042s (userspace) = 12.087s. Sep 4 16:16:55.411527 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 16:16:55.413165 systemd[1]: Started sshd@0-10.0.0.15:22-10.0.0.1:59892.service - OpenSSH per-connection server daemon (10.0.0.1:59892). Sep 4 16:16:55.495151 sshd[1661]: Accepted publickey for core from 10.0.0.1 port 59892 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:55.497699 sshd-session[1661]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:55.504835 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 16:16:55.506099 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 16:16:55.512333 systemd-logind[1523]: New session 1 of user core. Sep 4 16:16:55.539662 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 16:16:55.543403 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 16:16:55.569175 (systemd)[1666]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 16:16:55.571853 systemd-logind[1523]: New session c1 of user core. Sep 4 16:16:55.723998 systemd[1666]: Queued start job for default target default.target. Sep 4 16:16:55.743420 systemd[1666]: Created slice app.slice - User Application Slice. Sep 4 16:16:55.743457 systemd[1666]: Reached target paths.target - Paths. Sep 4 16:16:55.743508 systemd[1666]: Reached target timers.target - Timers. Sep 4 16:16:55.745363 systemd[1666]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 16:16:55.759065 systemd[1666]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 16:16:55.759220 systemd[1666]: Reached target sockets.target - Sockets. Sep 4 16:16:55.759273 systemd[1666]: Reached target basic.target - Basic System. Sep 4 16:16:55.759338 systemd[1666]: Reached target default.target - Main User Target. Sep 4 16:16:55.759375 systemd[1666]: Startup finished in 179ms. Sep 4 16:16:55.759564 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 16:16:55.761329 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 16:16:55.823850 systemd[1]: Started sshd@1-10.0.0.15:22-10.0.0.1:59904.service - OpenSSH per-connection server daemon (10.0.0.1:59904). Sep 4 16:16:55.871971 sshd[1677]: Accepted publickey for core from 10.0.0.1 port 59904 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:55.873811 sshd-session[1677]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:55.878614 systemd-logind[1523]: New session 2 of user core. Sep 4 16:16:55.888041 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 16:16:55.943163 sshd[1680]: Connection closed by 10.0.0.1 port 59904 Sep 4 16:16:55.943643 sshd-session[1677]: pam_unix(sshd:session): session closed for user core Sep 4 16:16:55.956640 systemd[1]: sshd@1-10.0.0.15:22-10.0.0.1:59904.service: Deactivated successfully. Sep 4 16:16:55.958575 systemd[1]: session-2.scope: Deactivated successfully. Sep 4 16:16:55.959346 systemd-logind[1523]: Session 2 logged out. Waiting for processes to exit. Sep 4 16:16:55.962092 systemd[1]: Started sshd@2-10.0.0.15:22-10.0.0.1:59920.service - OpenSSH per-connection server daemon (10.0.0.1:59920). Sep 4 16:16:55.962916 systemd-logind[1523]: Removed session 2. Sep 4 16:16:56.028460 sshd[1686]: Accepted publickey for core from 10.0.0.1 port 59920 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:56.030100 sshd-session[1686]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:56.034662 systemd-logind[1523]: New session 3 of user core. Sep 4 16:16:56.054063 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 16:16:56.104706 sshd[1689]: Connection closed by 10.0.0.1 port 59920 Sep 4 16:16:56.105265 sshd-session[1686]: pam_unix(sshd:session): session closed for user core Sep 4 16:16:56.119920 systemd[1]: sshd@2-10.0.0.15:22-10.0.0.1:59920.service: Deactivated successfully. Sep 4 16:16:56.121878 systemd[1]: session-3.scope: Deactivated successfully. Sep 4 16:16:56.122804 systemd-logind[1523]: Session 3 logged out. Waiting for processes to exit. Sep 4 16:16:56.126165 systemd[1]: Started sshd@3-10.0.0.15:22-10.0.0.1:59928.service - OpenSSH per-connection server daemon (10.0.0.1:59928). Sep 4 16:16:56.126952 systemd-logind[1523]: Removed session 3. Sep 4 16:16:56.189128 sshd[1695]: Accepted publickey for core from 10.0.0.1 port 59928 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:56.190394 sshd-session[1695]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:56.194778 systemd-logind[1523]: New session 4 of user core. Sep 4 16:16:56.202029 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 16:16:56.256983 sshd[1698]: Connection closed by 10.0.0.1 port 59928 Sep 4 16:16:56.257367 sshd-session[1695]: pam_unix(sshd:session): session closed for user core Sep 4 16:16:56.270537 systemd[1]: sshd@3-10.0.0.15:22-10.0.0.1:59928.service: Deactivated successfully. Sep 4 16:16:56.272301 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 16:16:56.273147 systemd-logind[1523]: Session 4 logged out. Waiting for processes to exit. Sep 4 16:16:56.275630 systemd[1]: Started sshd@4-10.0.0.15:22-10.0.0.1:59944.service - OpenSSH per-connection server daemon (10.0.0.1:59944). Sep 4 16:16:56.276497 systemd-logind[1523]: Removed session 4. Sep 4 16:16:56.333794 sshd[1704]: Accepted publickey for core from 10.0.0.1 port 59944 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:56.335305 sshd-session[1704]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:56.340281 systemd-logind[1523]: New session 5 of user core. Sep 4 16:16:56.353043 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 16:16:56.414011 sudo[1708]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 4 16:16:56.414331 sudo[1708]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 16:16:56.433659 sudo[1708]: pam_unix(sudo:session): session closed for user root Sep 4 16:16:56.435607 sshd[1707]: Connection closed by 10.0.0.1 port 59944 Sep 4 16:16:56.436127 sshd-session[1704]: pam_unix(sshd:session): session closed for user core Sep 4 16:16:56.449712 systemd[1]: sshd@4-10.0.0.15:22-10.0.0.1:59944.service: Deactivated successfully. Sep 4 16:16:56.451649 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 16:16:56.452727 systemd-logind[1523]: Session 5 logged out. Waiting for processes to exit. Sep 4 16:16:56.456274 systemd[1]: Started sshd@5-10.0.0.15:22-10.0.0.1:59948.service - OpenSSH per-connection server daemon (10.0.0.1:59948). Sep 4 16:16:56.457053 systemd-logind[1523]: Removed session 5. Sep 4 16:16:56.512118 sshd[1714]: Accepted publickey for core from 10.0.0.1 port 59948 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:56.513583 sshd-session[1714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:56.518071 systemd-logind[1523]: New session 6 of user core. Sep 4 16:16:56.533053 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 4 16:16:56.588694 sudo[1719]: 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 4 16:16:56.589052 sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 16:16:56.596143 sudo[1719]: pam_unix(sudo:session): session closed for user root Sep 4 16:16:56.603502 sudo[1718]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 4 16:16:56.603825 sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 16:16:56.614711 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 16:16:56.666087 augenrules[1741]: No rules Sep 4 16:16:56.667815 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 16:16:56.668144 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 16:16:56.669503 sudo[1718]: pam_unix(sudo:session): session closed for user root Sep 4 16:16:56.671349 sshd[1717]: Connection closed by 10.0.0.1 port 59948 Sep 4 16:16:56.671728 sshd-session[1714]: pam_unix(sshd:session): session closed for user core Sep 4 16:16:56.680588 systemd[1]: sshd@5-10.0.0.15:22-10.0.0.1:59948.service: Deactivated successfully. Sep 4 16:16:56.682459 systemd[1]: session-6.scope: Deactivated successfully. Sep 4 16:16:56.683234 systemd-logind[1523]: Session 6 logged out. Waiting for processes to exit. Sep 4 16:16:56.686319 systemd[1]: Started sshd@6-10.0.0.15:22-10.0.0.1:59960.service - OpenSSH per-connection server daemon (10.0.0.1:59960). Sep 4 16:16:56.687117 systemd-logind[1523]: Removed session 6. Sep 4 16:16:56.743016 sshd[1750]: Accepted publickey for core from 10.0.0.1 port 59960 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:56.744246 sshd-session[1750]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:56.748778 systemd-logind[1523]: New session 7 of user core. Sep 4 16:16:56.763035 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 4 16:16:56.820558 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 4 16:16:56.834220 (dockerd)[1755]: 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 4 16:16:57.239790 dockerd[1755]: time="2025-09-04T16:16:57.239689786Z" level=info msg="Starting up" Sep 4 16:16:57.240744 dockerd[1755]: time="2025-09-04T16:16:57.240687554Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 4 16:16:57.355741 dockerd[1755]: time="2025-09-04T16:16:57.355671863Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 4 16:16:57.416459 dockerd[1755]: time="2025-09-04T16:16:57.416402111Z" level=info msg="Loading containers: start." Sep 4 16:16:57.428055 kernel: Initializing XFRM netlink socket Sep 4 16:16:57.695934 systemd-networkd[1457]: docker0: Link UP Sep 4 16:16:57.700274 dockerd[1755]: time="2025-09-04T16:16:57.700237442Z" level=info msg="Loading containers: done." Sep 4 16:16:57.717145 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4163317639-merged.mount: Deactivated successfully. Sep 4 16:16:57.718125 dockerd[1755]: time="2025-09-04T16:16:57.718090879Z" 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 4 16:16:57.718188 dockerd[1755]: time="2025-09-04T16:16:57.718176010Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 4 16:16:57.718280 dockerd[1755]: time="2025-09-04T16:16:57.718266240Z" level=info msg="Initializing buildkit" Sep 4 16:16:57.748383 dockerd[1755]: time="2025-09-04T16:16:57.748351395Z" level=info msg="Completed buildkit initialization" Sep 4 16:16:57.754114 dockerd[1755]: time="2025-09-04T16:16:57.754087114Z" level=info msg="Daemon has completed initialization" Sep 4 16:16:57.754187 dockerd[1755]: time="2025-09-04T16:16:57.754137708Z" level=info msg="API listen on /run/docker.sock" Sep 4 16:16:57.754434 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 4 16:16:57.767569 sshd[1753]: Connection closed by 10.0.0.1 port 59960 Sep 4 16:16:57.767962 sshd-session[1750]: pam_unix(sshd:session): session closed for user core Sep 4 16:16:57.781677 systemd[1]: sshd@6-10.0.0.15:22-10.0.0.1:59960.service: Deactivated successfully. Sep 4 16:16:57.783657 systemd[1]: session-7.scope: Deactivated successfully. Sep 4 16:16:57.784424 systemd-logind[1523]: Session 7 logged out. Waiting for processes to exit. Sep 4 16:16:57.787248 systemd[1]: Started sshd@7-10.0.0.15:22-10.0.0.1:59970.service - OpenSSH per-connection server daemon (10.0.0.1:59970). Sep 4 16:16:57.787790 systemd-logind[1523]: Removed session 7. Sep 4 16:16:57.850293 sshd[1969]: Accepted publickey for core from 10.0.0.1 port 59970 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:16:57.851994 sshd-session[1969]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:16:57.856461 systemd-logind[1523]: New session 8 of user core. Sep 4 16:16:57.867038 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 4 16:17:01.361236 systemd[1]: var-lib-docker-overlay2-8bfd7bfc8825a431cd468728f81b8604829d8fe12753fa4c3fd4d64f02922bb9\x2dinit-merged.mount: Deactivated successfully. Sep 4 16:17:01.417914 containerd[1556]: time="2025-09-04T16:17:01.416830314Z" level=info msg="connecting to shim 957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f" address="unix:///run/containerd/s/241f9262421916f50739997e5320339cc59740b714807152c588a7fadfd53adb" namespace=moby protocol=ttrpc version=3 Sep 4 16:17:01.442164 systemd[1]: Started docker-957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f.scope - libcontainer container 957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f. Sep 4 16:17:01.456447 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 16:17:01.485450 kernel: docker0: port 1(vethd0681be) entered blocking state Sep 4 16:17:01.485548 kernel: docker0: port 1(vethd0681be) entered disabled state Sep 4 16:17:01.485572 kernel: vethd0681be: entered allmulticast mode Sep 4 16:17:01.485593 kernel: vethd0681be: entered promiscuous mode Sep 4 16:17:01.486324 systemd-networkd[1457]: vethd0681be: Link UP Sep 4 16:17:01.495974 kernel: eth0: renamed from veth1603d2d Sep 4 16:17:01.499572 kernel: docker0: port 1(vethd0681be) entered blocking state Sep 4 16:17:01.499625 kernel: docker0: port 1(vethd0681be) entered forwarding state Sep 4 16:17:01.499378 systemd-networkd[1457]: vethd0681be: Gained carrier Sep 4 16:17:01.500206 systemd-networkd[1457]: docker0: Gained carrier Sep 4 16:17:01.532118 sshd[1972]: Connection closed by 10.0.0.1 port 59970 Sep 4 16:17:01.533451 sshd-session[1969]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:01.550523 systemd[1]: sshd@7-10.0.0.15:22-10.0.0.1:59970.service: Deactivated successfully. Sep 4 16:17:01.552302 systemd[1]: session-8.scope: Deactivated successfully. Sep 4 16:17:01.553014 systemd-logind[1523]: Session 8 logged out. Waiting for processes to exit. Sep 4 16:17:01.555798 systemd[1]: Started sshd@8-10.0.0.15:22-10.0.0.1:51160.service - OpenSSH per-connection server daemon (10.0.0.1:51160). Sep 4 16:17:01.556358 systemd-logind[1523]: Removed session 8. Sep 4 16:17:01.619423 sshd[2048]: Accepted publickey for core from 10.0.0.1 port 51160 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:01.620735 sshd-session[2048]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:01.624949 systemd-logind[1523]: New session 9 of user core. Sep 4 16:17:01.642037 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 4 16:17:01.699213 sshd[2051]: Connection closed by 10.0.0.1 port 51160 Sep 4 16:17:01.699589 sshd-session[2048]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:01.718337 systemd[1]: sshd@8-10.0.0.15:22-10.0.0.1:51160.service: Deactivated successfully. Sep 4 16:17:01.720250 systemd[1]: session-9.scope: Deactivated successfully. Sep 4 16:17:01.720986 systemd-logind[1523]: Session 9 logged out. Waiting for processes to exit. Sep 4 16:17:01.723646 systemd[1]: Started sshd@9-10.0.0.15:22-10.0.0.1:51166.service - OpenSSH per-connection server daemon (10.0.0.1:51166). Sep 4 16:17:01.724201 systemd-logind[1523]: Removed session 9. Sep 4 16:17:01.784213 sshd[2057]: Accepted publickey for core from 10.0.0.1 port 51166 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:01.785448 sshd-session[2057]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:01.789496 systemd-logind[1523]: New session 10 of user core. Sep 4 16:17:01.799019 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 4 16:17:01.853545 sudo[2061]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGTERM 1556 Sep 4 16:17:01.853866 sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 16:17:01.857899 containerd[1556]: time="2025-09-04T16:17:01.857839814Z" level=info msg="Stop CRI service" Sep 4 16:17:01.858038 dockerd[1755]: time="2025-09-04T16:17:01.857971387Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 4 16:17:01.858038 dockerd[1755]: time="2025-09-04T16:17:01.858017392Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 4 16:17:01.858325 dockerd[1755]: time="2025-09-04T16:17:01.858269325Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 4 16:17:01.858325 dockerd[1755]: time="2025-09-04T16:17:01.858301548Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 4 16:17:01.858484 sudo[2061]: pam_unix(sudo:session): session closed for user root Sep 4 16:17:01.860131 sshd[2060]: Connection closed by 10.0.0.1 port 51166 Sep 4 16:17:01.860534 sshd-session[2057]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:01.863141 containerd[1556]: time="2025-09-04T16:17:01.863057031Z" level=info msg="Stop CRI service" Sep 4 16:17:01.863141 containerd[1556]: time="2025-09-04T16:17:01.863128262Z" level=info msg="Event monitor stopped" Sep 4 16:17:01.863141 containerd[1556]: time="2025-09-04T16:17:01.863134572Z" level=info msg="Stream server stopped" Sep 4 16:17:01.870304 systemd[1]: containerd.service: Deactivated successfully. Sep 4 16:17:01.870415 systemd[1]: containerd.service: Unit process 1997 (containerd-shim) remains running after unit stopped. Sep 4 16:17:01.870686 systemd[1]: containerd.service: Consumed 527ms CPU time, 125.2M memory peak. Sep 4 16:17:01.871175 systemd[1]: sshd@9-10.0.0.15:22-10.0.0.1:51166.service: Deactivated successfully. Sep 4 16:17:01.873040 systemd[1]: session-10.scope: Deactivated successfully. Sep 4 16:17:01.873742 systemd-logind[1523]: Session 10 logged out. Waiting for processes to exit. Sep 4 16:17:01.877207 systemd[1]: Started sshd@10-10.0.0.15:22-10.0.0.1:51172.service - OpenSSH per-connection server daemon (10.0.0.1:51172). Sep 4 16:17:01.877848 systemd-logind[1523]: Removed session 10. Sep 4 16:17:01.933755 sshd[2068]: Accepted publickey for core from 10.0.0.1 port 51172 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:01.935421 sshd-session[2068]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:01.939533 systemd-logind[1523]: New session 11 of user core. Sep 4 16:17:01.949000 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 4 16:17:02.005265 sshd[2071]: Connection closed by 10.0.0.1 port 51172 Sep 4 16:17:02.005577 sshd-session[2068]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:02.009512 systemd[1]: sshd@10-10.0.0.15:22-10.0.0.1:51172.service: Deactivated successfully. Sep 4 16:17:02.011497 systemd[1]: session-11.scope: Deactivated successfully. Sep 4 16:17:02.013026 systemd-logind[1523]: Session 11 logged out. Waiting for processes to exit. Sep 4 16:17:02.014333 systemd-logind[1523]: Removed session 11. Sep 4 16:17:02.757183 systemd-networkd[1457]: vethd0681be: Gained IPv6LL Sep 4 16:17:03.269066 systemd-networkd[1457]: docker0: Gained IPv6LL Sep 4 16:17:06.935722 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 1. Sep 4 16:17:06.936836 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 4 16:17:06.937353 dockerd[1755]: time="2025-09-04T16:17:06.936969200Z" level=info msg="Processing signal 'terminated'" Sep 4 16:17:08.057155 systemd[1]: Started sshd@11-10.0.0.15:22-10.0.0.1:51186.service - OpenSSH per-connection server daemon (10.0.0.1:51186). Sep 4 16:17:08.114382 sshd[2077]: Accepted publickey for core from 10.0.0.1 port 51186 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:08.115925 sshd-session[2077]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:08.121016 systemd-logind[1523]: New session 12 of user core. Sep 4 16:17:08.132013 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 4 16:17:08.190636 sshd[2080]: Connection closed by 10.0.0.1 port 51186 Sep 4 16:17:08.190983 sshd-session[2077]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:08.194783 systemd[1]: sshd@11-10.0.0.15:22-10.0.0.1:51186.service: Deactivated successfully. Sep 4 16:17:08.196621 systemd[1]: session-12.scope: Deactivated successfully. Sep 4 16:17:08.198053 systemd-logind[1523]: Session 12 logged out. Waiting for processes to exit. Sep 4 16:17:08.199207 systemd-logind[1523]: Removed session 12. Sep 4 16:17:14.222355 systemd[1]: Started sshd@12-10.0.0.15:22-10.0.0.1:47610.service - OpenSSH per-connection server daemon (10.0.0.1:47610). Sep 4 16:17:14.285851 sshd[2086]: Accepted publickey for core from 10.0.0.1 port 47610 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:14.287597 sshd-session[2086]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:14.293088 systemd-logind[1523]: New session 13 of user core. Sep 4 16:17:14.300037 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 4 16:17:14.358120 sshd[2089]: Connection closed by 10.0.0.1 port 47610 Sep 4 16:17:14.358458 sshd-session[2086]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:14.363689 systemd[1]: sshd@12-10.0.0.15:22-10.0.0.1:47610.service: Deactivated successfully. Sep 4 16:17:14.365820 systemd[1]: session-13.scope: Deactivated successfully. Sep 4 16:17:14.366697 systemd-logind[1523]: Session 13 logged out. Waiting for processes to exit. Sep 4 16:17:14.368133 systemd-logind[1523]: Removed session 13. Sep 4 16:17:20.390741 systemd[1]: Started sshd@13-10.0.0.15:22-10.0.0.1:43930.service - OpenSSH per-connection server daemon (10.0.0.1:43930). Sep 4 16:17:20.443786 sshd[2095]: Accepted publickey for core from 10.0.0.1 port 43930 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:20.445039 sshd-session[2095]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:20.449274 systemd-logind[1523]: New session 14 of user core. Sep 4 16:17:20.460004 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 4 16:17:20.515568 sshd[2098]: Connection closed by 10.0.0.1 port 43930 Sep 4 16:17:20.515953 sshd-session[2095]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:20.520620 systemd[1]: sshd@13-10.0.0.15:22-10.0.0.1:43930.service: Deactivated successfully. Sep 4 16:17:20.522637 systemd[1]: session-14.scope: Deactivated successfully. Sep 4 16:17:20.523410 systemd-logind[1523]: Session 14 logged out. Waiting for processes to exit. Sep 4 16:17:20.524472 systemd-logind[1523]: Removed session 14. Sep 4 16:17:21.938675 dockerd[1755]: time="2025-09-04T16:17:21.938531532Z" level=error msg="Force shutdown daemon" Sep 4 16:17:21.939204 dockerd[1755]: time="2025-09-04T16:17:21.938898500Z" level=info msg="Daemon shutdown complete" Sep 4 16:17:21.943919 systemd[1]: docker.service: Deactivated successfully. Sep 4 16:17:21.944392 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 4 16:17:21.944468 systemd[1]: docker.service: Consumed 1.376s CPU time, 128.1M memory peak. Sep 4 16:17:21.945093 systemd[1]: docker.socket: Deactivated successfully. Sep 4 16:17:21.945500 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 4 16:17:21.945561 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 4 16:17:21.947621 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 16:17:21.947853 systemd[1]: containerd.service: Found left-over process 1997 (containerd-shim) in control group while starting unit. Ignoring. Sep 4 16:17:21.947864 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 4 16:17:21.954463 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 16:17:21.954990 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 16:17:21.997380 systemd[1]: containerd.service: Found left-over process 1997 (containerd-shim) in control group while starting unit. Ignoring. Sep 4 16:17:21.997390 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 4 16:17:22.002537 (ntainerd)[2109]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 16:17:22.026826 containerd[2109]: time="2025-09-04T16:17:22Z" 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 4 16:17:22.027605 containerd[2109]: time="2025-09-04T16:17:22.027548555Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 4 16:17:22.036502 containerd[2109]: time="2025-09-04T16:17:22.036436637Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="40.384µs" Sep 4 16:17:22.036545 containerd[2109]: time="2025-09-04T16:17:22.036509192Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 4 16:17:22.036584 containerd[2109]: time="2025-09-04T16:17:22.036553938Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 4 16:17:22.036606 containerd[2109]: time="2025-09-04T16:17:22.036592277Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 4 16:17:22.036633 containerd[2109]: time="2025-09-04T16:17:22.036605846Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 4 16:17:22.036937 containerd[2109]: time="2025-09-04T16:17:22.036902935Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 16:17:22.036970 containerd[2109]: time="2025-09-04T16:17:22.036934736Z" 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 4 16:17:22.036970 containerd[2109]: time="2025-09-04T16:17:22.036945055Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 4 16:17:22.037250 containerd[2109]: time="2025-09-04T16:17:22.037218809Z" 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 4 16:17:22.037250 containerd[2109]: time="2025-09-04T16:17:22.037236199Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 16:17:22.037250 containerd[2109]: time="2025-09-04T16:17:22.037247521Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 16:17:22.037320 containerd[2109]: time="2025-09-04T16:17:22.037257228Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 4 16:17:22.037320 containerd[2109]: time="2025-09-04T16:17:22.037277265Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 4 16:17:22.037435 containerd[2109]: time="2025-09-04T16:17:22.037406299Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 4 16:17:22.037467 containerd[2109]: time="2025-09-04T16:17:22.037438491Z" 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 4 16:17:22.037467 containerd[2109]: time="2025-09-04T16:17:22.037448880Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 4 16:17:22.037509 containerd[2109]: time="2025-09-04T16:17:22.037485464Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 4 16:17:22.037830 containerd[2109]: time="2025-09-04T16:17:22.037760552Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 4 16:17:22.037868 containerd[2109]: time="2025-09-04T16:17:22.037847759Z" level=info msg="metadata content store policy set" policy=shared Sep 4 16:17:22.038135 containerd[2109]: time="2025-09-04T16:17:22.038103412Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 4 16:17:22.038172 containerd[2109]: time="2025-09-04T16:17:22.038157365Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 4 16:17:22.038193 containerd[2109]: time="2025-09-04T16:17:22.038175806Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 4 16:17:22.038216 containerd[2109]: time="2025-09-04T16:17:22.038192093Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 4 16:17:22.038236 containerd[2109]: time="2025-09-04T16:17:22.038215178Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 4 16:17:22.038236 containerd[2109]: time="2025-09-04T16:17:22.038228857Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 4 16:17:22.038284 containerd[2109]: time="2025-09-04T16:17:22.038242275Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 4 16:17:22.038284 containerd[2109]: time="2025-09-04T16:17:22.038260075Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 4 16:17:22.038284 containerd[2109]: time="2025-09-04T16:17:22.038271768Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 4 16:17:22.038284 containerd[2109]: time="2025-09-04T16:17:22.038282127Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 4 16:17:22.038390 containerd[2109]: time="2025-09-04T16:17:22.038291484Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 4 16:17:22.038390 containerd[2109]: time="2025-09-04T16:17:22.038308331Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 4 16:17:22.038727 containerd[2109]: time="2025-09-04T16:17:22.038692418Z" level=info msg="connecting to shim 957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f" address="unix:///run/containerd/s/241f9262421916f50739997e5320339cc59740b714807152c588a7fadfd53adb" namespace=moby protocol=ttrpc version=3 Sep 4 16:17:22.051143 containerd[2109]: time="2025-09-04T16:17:22.051104357Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 4 16:17:22.051200 containerd[2109]: time="2025-09-04T16:17:22.051149996Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 4 16:17:22.051200 containerd[2109]: time="2025-09-04T16:17:22.051176000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 4 16:17:22.051200 containerd[2109]: time="2025-09-04T16:17:22.051191152Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 4 16:17:22.051262 containerd[2109]: time="2025-09-04T16:17:22.051201662Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 4 16:17:22.051262 containerd[2109]: time="2025-09-04T16:17:22.051213526Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 4 16:17:22.051262 containerd[2109]: time="2025-09-04T16:17:22.051223996Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 4 16:17:22.051262 containerd[2109]: time="2025-09-04T16:17:22.051233733Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 4 16:17:22.051262 containerd[2109]: time="2025-09-04T16:17:22.051254120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 4 16:17:22.051379 containerd[2109]: time="2025-09-04T16:17:22.051265974Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 4 16:17:22.051379 containerd[2109]: time="2025-09-04T16:17:22.051276142Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 4 16:17:22.051379 containerd[2109]: time="2025-09-04T16:17:22.051367401Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 4 16:17:22.051447 containerd[2109]: time="2025-09-04T16:17:22.051381581Z" level=info msg="Start snapshots syncer" Sep 4 16:17:22.051447 containerd[2109]: time="2025-09-04T16:17:22.051422828Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 4 16:17:22.051704 containerd[2109]: time="2025-09-04T16:17:22.051652778Z" 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 4 16:17:22.051891 containerd[2109]: time="2025-09-04T16:17:22.051715998Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 4 16:17:22.051891 containerd[2109]: time="2025-09-04T16:17:22.051804187Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 4 16:17:22.051891 containerd[2109]: time="2025-09-04T16:17:22.051864357Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 4 16:17:22.051964 containerd[2109]: time="2025-09-04T16:17:22.051901963Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 4 16:17:22.051964 containerd[2109]: time="2025-09-04T16:17:22.051914659Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 4 16:17:22.051964 containerd[2109]: time="2025-09-04T16:17:22.051925128Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 4 16:17:22.051964 containerd[2109]: time="2025-09-04T16:17:22.051943351Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 4 16:17:22.051964 containerd[2109]: time="2025-09-04T16:17:22.051953438Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 4 16:17:22.051964 containerd[2109]: time="2025-09-04T16:17:22.051964520Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.051990905Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052001324Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052011091Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052049861Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052063389Z" 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 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052072636Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052081391Z" 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 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052091951Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 4 16:17:22.052098 containerd[2109]: time="2025-09-04T16:17:22.052101378Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 4 16:17:22.052281 containerd[2109]: time="2025-09-04T16:17:22.052130369Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 4 16:17:22.052281 containerd[2109]: time="2025-09-04T16:17:22.052162500Z" level=info msg="runtime interface created" Sep 4 16:17:22.052281 containerd[2109]: time="2025-09-04T16:17:22.052167895Z" level=info msg="created NRI interface" Sep 4 16:17:22.052281 containerd[2109]: time="2025-09-04T16:17:22.052176671Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 4 16:17:22.052281 containerd[2109]: time="2025-09-04T16:17:22.052186969Z" level=info msg="Connect containerd service" Sep 4 16:17:22.052281 containerd[2109]: time="2025-09-04T16:17:22.052251662Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 16:17:22.053064 containerd[2109]: time="2025-09-04T16:17:22.053023837Z" 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 4 16:17:22.085921 containerd[2109]: time="2025-09-04T16:17:22.085859027Z" level=info msg="Start subscribing containerd event" Sep 4 16:17:22.086154 containerd[2109]: time="2025-09-04T16:17:22.086070585Z" level=info msg="Start recovering state" Sep 4 16:17:22.086154 containerd[2109]: time="2025-09-04T16:17:22.086127456Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086182532Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086229685Z" level=info msg="Start event monitor" Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086250805Z" level=info msg="Start cni network conf syncer for default" Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086264183Z" level=info msg="Start streaming server" Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086280148Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086287950Z" level=info msg="runtime interface starting up..." Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086296565Z" level=info msg="starting plugins..." Sep 4 16:17:22.086330 containerd[2109]: time="2025-09-04T16:17:22.086312861Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 4 16:17:22.086529 containerd[2109]: time="2025-09-04T16:17:22.086506549Z" level=info msg="containerd successfully booted in 0.059911s" Sep 4 16:17:22.087499 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 16:17:22.089349 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 4 16:17:22.113240 (dockerd)[2137]: 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 4 16:17:22.155413 dockerd[2137]: time="2025-09-04T16:17:22.155349413Z" level=info msg="Starting up" Sep 4 16:17:22.156163 dockerd[2137]: time="2025-09-04T16:17:22.156129399Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 4 16:17:22.168612 dockerd[2137]: time="2025-09-04T16:17:22.168572507Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 4 16:17:22.189684 dockerd[2137]: time="2025-09-04T16:17:22.189591714Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 4 16:17:22.213864 dockerd[2137]: time="2025-09-04T16:17:22.213807793Z" level=info msg="Loading containers: start." Sep 4 16:17:26.537728 systemd[1]: Started sshd@14-10.0.0.15:22-10.0.0.1:43944.service - OpenSSH per-connection server daemon (10.0.0.1:43944). Sep 4 16:17:26.592151 sshd[2170]: Accepted publickey for core from 10.0.0.1 port 43944 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:26.593810 sshd-session[2170]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:26.598426 systemd-logind[1523]: New session 15 of user core. Sep 4 16:17:26.612043 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 4 16:17:26.668436 sshd[2173]: Connection closed by 10.0.0.1 port 43944 Sep 4 16:17:26.668834 sshd-session[2170]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:26.678055 systemd[1]: sshd@14-10.0.0.15:22-10.0.0.1:43944.service: Deactivated successfully. Sep 4 16:17:26.679967 systemd[1]: session-15.scope: Deactivated successfully. Sep 4 16:17:26.680769 systemd-logind[1523]: Session 15 logged out. Waiting for processes to exit. Sep 4 16:17:26.684260 systemd[1]: Started sshd@15-10.0.0.15:22-10.0.0.1:43956.service - OpenSSH per-connection server daemon (10.0.0.1:43956). Sep 4 16:17:26.684961 systemd-logind[1523]: Removed session 15. Sep 4 16:17:26.751729 sshd[2179]: Accepted publickey for core from 10.0.0.1 port 43956 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:26.753033 sshd-session[2179]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:26.757099 systemd-logind[1523]: New session 16 of user core. Sep 4 16:17:26.766997 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 4 16:17:26.822066 sshd[2182]: Connection closed by 10.0.0.1 port 43956 Sep 4 16:17:26.822316 sshd-session[2179]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:26.834159 systemd[1]: sshd@15-10.0.0.15:22-10.0.0.1:43956.service: Deactivated successfully. Sep 4 16:17:26.835707 systemd[1]: session-16.scope: Deactivated successfully. Sep 4 16:17:26.836466 systemd-logind[1523]: Session 16 logged out. Waiting for processes to exit. Sep 4 16:17:26.838913 systemd[1]: Started sshd@16-10.0.0.15:22-10.0.0.1:43960.service - OpenSSH per-connection server daemon (10.0.0.1:43960). Sep 4 16:17:26.839670 systemd-logind[1523]: Removed session 16. Sep 4 16:17:26.894281 sshd[2188]: Accepted publickey for core from 10.0.0.1 port 43960 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:26.895549 sshd-session[2188]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:26.899624 systemd-logind[1523]: New session 17 of user core. Sep 4 16:17:26.909983 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 4 16:17:32.241409 dockerd[2137]: time="2025-09-04T16:17:32.241302305Z" level=info msg="Container failed to exit within 10s of signal 15 - using the force" container=957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f Sep 4 16:17:32.254270 systemd[1]: docker-957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f.scope: Deactivated successfully. Sep 4 16:17:32.310603 containerd[2109]: time="2025-09-04T16:17:32.310531405Z" level=info msg="shim disconnected" id=957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f namespace=moby Sep 4 16:17:32.310603 containerd[2109]: time="2025-09-04T16:17:32.310581587Z" level=warning msg="cleaning up after shim disconnected" id=957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f namespace=moby Sep 4 16:17:32.311095 containerd[2109]: time="2025-09-04T16:17:32.310590238Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 16:17:32.327976 systemd[1]: var-lib-docker-overlay2-8bfd7bfc8825a431cd468728f81b8604829d8fe12753fa4c3fd4d64f02922bb9-merged.mount: Deactivated successfully. Sep 4 16:17:32.798136 systemd[1]: run-docker-netns-63fdb0eabea3.mount: Deactivated successfully. Sep 4 16:17:32.798532 systemd-networkd[1457]: vethd0681be: Link DOWN Sep 4 16:17:32.798929 kernel: docker0: port 1(vethd0681be) entered disabled state Sep 4 16:17:32.798546 systemd-networkd[1457]: vethd0681be: Lost carrier Sep 4 16:17:32.801584 kernel: vethd0681be (unregistering): left allmulticast mode Sep 4 16:17:32.801633 kernel: vethd0681be (unregistering): left promiscuous mode Sep 4 16:17:32.801658 kernel: docker0: port 1(vethd0681be) entered disabled state Sep 4 16:17:32.802381 systemd-networkd[1457]: docker0: Lost carrier Sep 4 16:17:32.805703 dockerd[2137]: time="2025-09-04T16:17:32.805324225Z" level=info msg="Removing stale sandbox 63fdb0eabea36612d60333411ceec42ecee7f326ac0954c8c678807a96c0141c (957f76f983a9b2c704be49fc89ef38d82ad71395f5ca98f11332d8fa17db2a5f)" Sep 4 16:17:32.810908 dockerd[2137]: time="2025-09-04T16:17:32.810845866Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 4 16:17:32.810908 dockerd[2137]: time="2025-09-04T16:17:32.810899116Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint bd260ce00d34e1b810a326b61bacf416cd6c19be0dca14450ffed9df57d02c1a ed89fe08f6415f0becb0f31e7d2dd325b0c81d7904df52c667c55698b4428cef], retrying...." Sep 4 16:17:32.814777 systemd[1]: run-docker-netns-63fdb0eabea3.mount: Deactivated successfully. Sep 4 16:17:32.991087 dockerd[2137]: time="2025-09-04T16:17:32.991017393Z" level=warning msg="error locating sandbox id 63fdb0eabea36612d60333411ceec42ecee7f326ac0954c8c678807a96c0141c: sandbox 63fdb0eabea36612d60333411ceec42ecee7f326ac0954c8c678807a96c0141c not found" Sep 4 16:17:32.991272 dockerd[2137]: time="2025-09-04T16:17:32.991155289Z" level=info msg="Loading containers: done." Sep 4 16:17:33.007192 dockerd[2137]: time="2025-09-04T16:17:33.007137194Z" 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 4 16:17:33.007428 dockerd[2137]: time="2025-09-04T16:17:33.007220995Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 4 16:17:33.007428 dockerd[2137]: time="2025-09-04T16:17:33.007260330Z" level=info msg="Initializing buildkit" Sep 4 16:17:33.024485 dockerd[2137]: time="2025-09-04T16:17:33.024414072Z" level=info msg="Completed buildkit initialization" Sep 4 16:17:33.030615 dockerd[2137]: time="2025-09-04T16:17:33.030580259Z" level=info msg="Daemon has completed initialization" Sep 4 16:17:33.030735 dockerd[2137]: time="2025-09-04T16:17:33.030654808Z" level=info msg="API listen on /run/docker.sock" Sep 4 16:17:33.031774 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 4 16:17:33.041210 sshd[2191]: Connection closed by 10.0.0.1 port 43960 Sep 4 16:17:33.041648 sshd-session[2188]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:33.056182 systemd[1]: sshd@16-10.0.0.15:22-10.0.0.1:43960.service: Deactivated successfully. Sep 4 16:17:33.058272 systemd[1]: session-17.scope: Deactivated successfully. Sep 4 16:17:33.059153 systemd-logind[1523]: Session 17 logged out. Waiting for processes to exit. Sep 4 16:17:33.062494 systemd[1]: Started sshd@17-10.0.0.15:22-10.0.0.1:44616.service - OpenSSH per-connection server daemon (10.0.0.1:44616). Sep 4 16:17:33.063128 systemd-logind[1523]: Removed session 17. Sep 4 16:17:33.121283 sshd[2442]: Accepted publickey for core from 10.0.0.1 port 44616 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:33.123413 sshd-session[2442]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:33.128547 systemd-logind[1523]: New session 18 of user core. Sep 4 16:17:33.138054 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 4 16:17:33.260824 containerd[2109]: time="2025-09-04T16:17:33.260752447Z" level=info msg="connecting to shim 6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7" address="unix:///run/containerd/s/9ee2ef5551e6617df9df91235587e4435fd9a2bb6016421916f77c21663aafc6" namespace=moby protocol=ttrpc version=3 Sep 4 16:17:33.293013 systemd[1]: Started docker-6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7.scope - libcontainer container 6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7. Sep 4 16:17:33.306730 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 16:17:33.329510 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3846257849-merged.mount: Deactivated successfully. Sep 4 16:17:33.332144 kernel: docker0: port 1(vethd9951c6) entered blocking state Sep 4 16:17:33.332207 kernel: docker0: port 1(vethd9951c6) entered disabled state Sep 4 16:17:33.335923 kernel: vethd9951c6: entered allmulticast mode Sep 4 16:17:33.336038 kernel: vethd9951c6: entered promiscuous mode Sep 4 16:17:33.335029 systemd-networkd[1457]: vethd9951c6: Link UP Sep 4 16:17:33.342922 kernel: eth0: renamed from veth1545ec3 Sep 4 16:17:33.345477 kernel: docker0: port 1(vethd9951c6) entered blocking state Sep 4 16:17:33.345539 kernel: docker0: port 1(vethd9951c6) entered forwarding state Sep 4 16:17:33.345547 systemd-networkd[1457]: vethd9951c6: Gained carrier Sep 4 16:17:33.345766 systemd-networkd[1457]: docker0: Gained carrier Sep 4 16:17:33.374099 sshd[2445]: Connection closed by 10.0.0.1 port 44616 Sep 4 16:17:33.374516 sshd-session[2442]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:33.388758 systemd[1]: sshd@17-10.0.0.15:22-10.0.0.1:44616.service: Deactivated successfully. Sep 4 16:17:33.390697 systemd[1]: session-18.scope: Deactivated successfully. Sep 4 16:17:33.391413 systemd-logind[1523]: Session 18 logged out. Waiting for processes to exit. Sep 4 16:17:33.394178 systemd[1]: Started sshd@18-10.0.0.15:22-10.0.0.1:44628.service - OpenSSH per-connection server daemon (10.0.0.1:44628). Sep 4 16:17:33.394749 systemd-logind[1523]: Removed session 18. Sep 4 16:17:33.445516 sshd[2513]: Accepted publickey for core from 10.0.0.1 port 44628 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:33.447167 sshd-session[2513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:33.451454 systemd-logind[1523]: New session 19 of user core. Sep 4 16:17:33.466095 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 4 16:17:33.522311 sshd[2516]: Connection closed by 10.0.0.1 port 44628 Sep 4 16:17:33.522685 sshd-session[2513]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:33.535622 systemd[1]: sshd@18-10.0.0.15:22-10.0.0.1:44628.service: Deactivated successfully. Sep 4 16:17:33.537547 systemd[1]: session-19.scope: Deactivated successfully. Sep 4 16:17:33.538274 systemd-logind[1523]: Session 19 logged out. Waiting for processes to exit. Sep 4 16:17:33.541383 systemd[1]: Started sshd@19-10.0.0.15:22-10.0.0.1:44644.service - OpenSSH per-connection server daemon (10.0.0.1:44644). Sep 4 16:17:33.541978 systemd-logind[1523]: Removed session 19. Sep 4 16:17:33.595908 sshd[2522]: Accepted publickey for core from 10.0.0.1 port 44644 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:33.597659 sshd-session[2522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:33.602337 systemd-logind[1523]: New session 20 of user core. Sep 4 16:17:33.619990 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 4 16:17:33.676656 sudo[2526]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/kill -SIGHUP 2109 Sep 4 16:17:33.677015 sudo[2526]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 16:17:33.680962 sudo[2526]: pam_unix(sudo:session): session closed for user root Sep 4 16:17:33.682764 sshd[2525]: Connection closed by 10.0.0.1 port 44644 Sep 4 16:17:33.683169 sshd-session[2522]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:33.685616 dockerd[2137]: time="2025-09-04T16:17:33.685557291Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Sep 4 16:17:33.685616 dockerd[2137]: time="2025-09-04T16:17:33.685592396Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Sep 4 16:17:33.687305 dockerd[2137]: time="2025-09-04T16:17:33.687254467Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=moby Sep 4 16:17:33.687305 dockerd[2137]: time="2025-09-04T16:17:33.687298953Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=moby Sep 4 16:17:33.691571 systemd[1]: containerd.service: Deactivated successfully. Sep 4 16:17:33.691674 systemd[1]: containerd.service: Unit process 2464 (containerd-shim) remains running after unit stopped. Sep 4 16:17:33.691960 systemd[1]: containerd.service: Consumed 304ms CPU time, 129M memory peak. Sep 4 16:17:33.692412 systemd[1]: sshd@19-10.0.0.15:22-10.0.0.1:44644.service: Deactivated successfully. Sep 4 16:17:33.694146 systemd[1]: session-20.scope: Deactivated successfully. Sep 4 16:17:33.694825 systemd-logind[1523]: Session 20 logged out. Waiting for processes to exit. Sep 4 16:17:33.698239 systemd[1]: Started sshd@20-10.0.0.15:22-10.0.0.1:44648.service - OpenSSH per-connection server daemon (10.0.0.1:44648). Sep 4 16:17:33.698803 systemd-logind[1523]: Removed session 20. Sep 4 16:17:33.772572 sshd[2533]: Accepted publickey for core from 10.0.0.1 port 44648 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:33.773992 sshd-session[2533]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:33.778164 systemd-logind[1523]: New session 21 of user core. Sep 4 16:17:33.788016 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 4 16:17:33.845446 sshd[2536]: Connection closed by 10.0.0.1 port 44648 Sep 4 16:17:33.845794 sshd-session[2533]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:33.850738 systemd[1]: sshd@20-10.0.0.15:22-10.0.0.1:44648.service: Deactivated successfully. Sep 4 16:17:33.852681 systemd[1]: session-21.scope: Deactivated successfully. Sep 4 16:17:33.853401 systemd-logind[1523]: Session 21 logged out. Waiting for processes to exit. Sep 4 16:17:33.854472 systemd-logind[1523]: Removed session 21. Sep 4 16:17:33.937217 update_engine[1525]: I20250904 16:17:33.937078 1525 update_attempter.cc:509] Updating boot flags... Sep 4 16:17:34.885191 systemd-networkd[1457]: vethd9951c6: Gained IPv6LL Sep 4 16:17:38.934430 systemd[1]: containerd.service: Scheduled restart job, restart counter is at 2. Sep 4 16:17:38.934821 systemd[1]: Stopping docker.service - Docker Application Container Engine... Sep 4 16:17:38.935079 dockerd[2137]: time="2025-09-04T16:17:38.935006440Z" level=info msg="Processing signal 'terminated'" Sep 4 16:17:39.873073 systemd[1]: Started sshd@21-10.0.0.15:22-10.0.0.1:44656.service - OpenSSH per-connection server daemon (10.0.0.1:44656). Sep 4 16:17:39.932365 sshd[2553]: Accepted publickey for core from 10.0.0.1 port 44656 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:39.934257 sshd-session[2553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:39.938768 systemd-logind[1523]: New session 22 of user core. Sep 4 16:17:39.948039 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 4 16:17:40.006331 sshd[2556]: Connection closed by 10.0.0.1 port 44656 Sep 4 16:17:40.006691 sshd-session[2553]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:40.010846 systemd[1]: sshd@21-10.0.0.15:22-10.0.0.1:44656.service: Deactivated successfully. Sep 4 16:17:40.012845 systemd[1]: session-22.scope: Deactivated successfully. Sep 4 16:17:40.013746 systemd-logind[1523]: Session 22 logged out. Waiting for processes to exit. Sep 4 16:17:40.015540 systemd-logind[1523]: Removed session 22. Sep 4 16:17:40.936736 dockerd[2137]: time="2025-09-04T16:17:40.936607951Z" level=error msg="Error sending stop (signal 15) to container" container=6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7 error="Cannot kill container 6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7: Unavailable: connection error: desc = \"transport: Error while dialing: dial unix /var/run/docker/libcontainerd/docker-containerd.sock: connect: connection refused\"" Sep 4 16:17:40.936736 dockerd[2137]: time="2025-09-04T16:17:40.936711865Z" level=info msg="Container failed to exit within 2s of signal 15 - using the force" container=6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7 Sep 4 16:17:46.024448 systemd[1]: Started sshd@22-10.0.0.15:22-10.0.0.1:51434.service - OpenSSH per-connection server daemon (10.0.0.1:51434). Sep 4 16:17:46.084558 sshd[2562]: Accepted publickey for core from 10.0.0.1 port 51434 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:46.086401 sshd-session[2562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:46.090912 systemd-logind[1523]: New session 23 of user core. Sep 4 16:17:46.105014 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 4 16:17:46.162598 sshd[2565]: Connection closed by 10.0.0.1 port 51434 Sep 4 16:17:46.163045 sshd-session[2562]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:46.168089 systemd[1]: sshd@22-10.0.0.15:22-10.0.0.1:51434.service: Deactivated successfully. Sep 4 16:17:46.170225 systemd[1]: session-23.scope: Deactivated successfully. Sep 4 16:17:46.171030 systemd-logind[1523]: Session 23 logged out. Waiting for processes to exit. Sep 4 16:17:46.172251 systemd-logind[1523]: Removed session 23. Sep 4 16:17:50.937754 dockerd[2137]: time="2025-09-04T16:17:50.937639627Z" level=warning msg="Container failed to exit within 10s of kill - trying direct SIGKILL" container=6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7 error="context deadline exceeded" Sep 4 16:17:50.940053 systemd[1]: docker-6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7.scope: Deactivated successfully. Sep 4 16:17:52.190500 systemd[1]: Started sshd@23-10.0.0.15:22-10.0.0.1:38110.service - OpenSSH per-connection server daemon (10.0.0.1:38110). Sep 4 16:17:52.260102 sshd[2572]: Accepted publickey for core from 10.0.0.1 port 38110 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:52.261607 sshd-session[2572]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:52.266865 systemd-logind[1523]: New session 24 of user core. Sep 4 16:17:52.282076 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 4 16:17:52.342480 sshd[2575]: Connection closed by 10.0.0.1 port 38110 Sep 4 16:17:52.342840 sshd-session[2572]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:52.348133 systemd[1]: sshd@23-10.0.0.15:22-10.0.0.1:38110.service: Deactivated successfully. Sep 4 16:17:52.350688 systemd[1]: session-24.scope: Deactivated successfully. Sep 4 16:17:52.351692 systemd-logind[1523]: Session 24 logged out. Waiting for processes to exit. Sep 4 16:17:52.353270 systemd-logind[1523]: Removed session 24. Sep 4 16:17:53.935634 dockerd[2137]: time="2025-09-04T16:17:53.935539031Z" level=error msg="Force shutdown daemon" Sep 4 16:17:53.936206 dockerd[2137]: time="2025-09-04T16:17:53.935718479Z" level=info msg="Daemon shutdown complete" Sep 4 16:17:53.942827 systemd[1]: docker.service: Deactivated successfully. Sep 4 16:17:53.943306 systemd[1]: Stopped docker.service - Docker Application Container Engine. Sep 4 16:17:53.943848 systemd[1]: docker.socket: Deactivated successfully. Sep 4 16:17:53.944256 systemd[1]: Closed docker.socket - Docker Socket for the API. Sep 4 16:17:53.944293 systemd[1]: Stopping docker.socket - Docker Socket for the API... Sep 4 16:17:53.945983 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 16:17:53.946223 systemd[1]: containerd.service: Found left-over process 2464 (containerd-shim) in control group while starting unit. Ignoring. Sep 4 16:17:53.946232 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 4 16:17:53.952357 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 16:17:53.953037 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 16:17:53.987503 systemd[1]: containerd.service: Found left-over process 2464 (containerd-shim) in control group while starting unit. Ignoring. Sep 4 16:17:53.987515 systemd[1]: containerd.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Sep 4 16:17:53.992984 (ntainerd)[2586]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 16:17:54.010222 containerd[2586]: time="2025-09-04T16:17:54Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 4 16:17:54.010865 containerd[2586]: time="2025-09-04T16:17:54.010802276Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 4 16:17:54.020017 containerd[2586]: time="2025-09-04T16:17:54.019953882Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="13.99µs" Sep 4 16:17:54.020017 containerd[2586]: time="2025-09-04T16:17:54.019999587Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 4 16:17:54.020143 containerd[2586]: time="2025-09-04T16:17:54.020025171Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 4 16:17:54.020143 containerd[2586]: time="2025-09-04T16:17:54.020070897Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 4 16:17:54.020143 containerd[2586]: time="2025-09-04T16:17:54.020088825Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 4 16:17:54.020406 containerd[2586]: time="2025-09-04T16:17:54.020369763Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020440 containerd[2586]: time="2025-09-04T16:17:54.020417783Z" 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 4 16:17:54.020440 containerd[2586]: time="2025-09-04T16:17:54.020434358Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020754 containerd[2586]: time="2025-09-04T16:17:54.020715046Z" 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 4 16:17:54.020754 containerd[2586]: time="2025-09-04T16:17:54.020739968Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020832 containerd[2586]: time="2025-09-04T16:17:54.020754980Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020832 containerd[2586]: time="2025-09-04T16:17:54.020766644Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020832 containerd[2586]: time="2025-09-04T16:17:54.020794653Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020958 containerd[2586]: time="2025-09-04T16:17:54.020934847Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 4 16:17:54.020994 containerd[2586]: time="2025-09-04T16:17:54.020970792Z" 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 4 16:17:54.020994 containerd[2586]: time="2025-09-04T16:17:54.020986354Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 4 16:17:54.021064 containerd[2586]: time="2025-09-04T16:17:54.021037461Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 4 16:17:54.021422 containerd[2586]: time="2025-09-04T16:17:54.021362231Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 4 16:17:54.021466 containerd[2586]: time="2025-09-04T16:17:54.021423981Z" level=info msg="metadata content store policy set" policy=shared Sep 4 16:17:54.021605 containerd[2586]: time="2025-09-04T16:17:54.021579166Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 4 16:17:54.021637 containerd[2586]: time="2025-09-04T16:17:54.021610502Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 4 16:17:54.021637 containerd[2586]: time="2025-09-04T16:17:54.021623800Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 4 16:17:54.021637 containerd[2586]: time="2025-09-04T16:17:54.021636086Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 4 16:17:54.021702 containerd[2586]: time="2025-09-04T16:17:54.021649603Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 4 16:17:54.021702 containerd[2586]: time="2025-09-04T16:17:54.021662109Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 4 16:17:54.021702 containerd[2586]: time="2025-09-04T16:17:54.021675808Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 4 16:17:54.021702 containerd[2586]: time="2025-09-04T16:17:54.021688795Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 4 16:17:54.021702 containerd[2586]: time="2025-09-04T16:17:54.021699889Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 4 16:17:54.021793 containerd[2586]: time="2025-09-04T16:17:54.021711753Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 4 16:17:54.021793 containerd[2586]: time="2025-09-04T16:17:54.021721143Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 4 16:17:54.021793 containerd[2586]: time="2025-09-04T16:17:54.021732708Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 4 16:17:54.023201 containerd[2586]: time="2025-09-04T16:17:54.023155367Z" level=info msg="connecting to shim 6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7" address="unix:///run/containerd/s/9ee2ef5551e6617df9df91235587e4435fd9a2bb6016421916f77c21663aafc6" namespace=moby protocol=ttrpc version=3 Sep 4 16:17:54.032150 containerd[2586]: time="2025-09-04T16:17:54.032110912Z" level=info msg="cleaning leaked shim process" id=6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7 namespace=moby Sep 4 16:17:54.042306 containerd[2586]: time="2025-09-04T16:17:54.042217019Z" level=error msg="post event" error="failed to connect: dial unix /run/containerd/containerd.sock.ttrpc: connect: connection refused" Sep 4 16:17:54.042764 containerd[2586]: time="2025-09-04T16:17:54.042732719Z" level=info msg="shim disconnected" id=6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7 namespace=moby Sep 4 16:17:54.042845 containerd[2586]: time="2025-09-04T16:17:54.042766359Z" level=warning msg="cleaning up after shim disconnected" id=6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7 namespace=moby Sep 4 16:17:54.042845 containerd[2586]: time="2025-09-04T16:17:54.042774636Z" level=info msg="cleaning up dead shim" namespace=moby Sep 4 16:17:54.057069 containerd[2586]: time="2025-09-04T16:17:54.056999678Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 4 16:17:54.057069 containerd[2586]: time="2025-09-04T16:17:54.057053240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 4 16:17:54.057069 containerd[2586]: time="2025-09-04T16:17:54.057071959Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057083484Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057095329Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057107524Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057118167Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057128278Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057148479Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057158570Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057168552Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 4 16:17:54.057258 containerd[2586]: time="2025-09-04T16:17:54.057249180Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 4 16:17:54.057479 containerd[2586]: time="2025-09-04T16:17:54.057267348Z" level=info msg="Start snapshots syncer" Sep 4 16:17:54.057479 containerd[2586]: time="2025-09-04T16:17:54.057302402Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 4 16:17:54.057605 containerd[2586]: time="2025-09-04T16:17:54.057565834Z" 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 4 16:17:54.057680 containerd[2586]: time="2025-09-04T16:17:54.057621921Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 4 16:17:54.057738 containerd[2586]: time="2025-09-04T16:17:54.057699734Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057778820Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057805155Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057818162Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057829536Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057843475Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057856001Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057867836Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057942051Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057958897Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.057971694Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.058017941Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.058034546Z" 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 4 16:17:54.058111 containerd[2586]: time="2025-09-04T16:17:54.058050218Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058062043Z" 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 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058071353Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058083307Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058094792Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058116778Z" level=info msg="runtime interface created" Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058124453Z" level=info msg="created NRI interface" Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058134033Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058199150Z" level=info msg="Connect containerd service" Sep 4 16:17:54.058510 containerd[2586]: time="2025-09-04T16:17:54.058234123Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 16:17:54.058912 containerd[2586]: time="2025-09-04T16:17:54.058835833Z" 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 4 16:17:54.070626 containerd[2586]: time="2025-09-04T16:17:54.070563835Z" level=info msg="Start subscribing containerd event" Sep 4 16:17:54.070707 containerd[2586]: time="2025-09-04T16:17:54.070651520Z" level=info msg="Start recovering state" Sep 4 16:17:54.070791 containerd[2586]: time="2025-09-04T16:17:54.070772653Z" level=info msg="Start event monitor" Sep 4 16:17:54.070791 containerd[2586]: time="2025-09-04T16:17:54.070789849Z" level=info msg="Start cni network conf syncer for default" Sep 4 16:17:54.070844 containerd[2586]: time="2025-09-04T16:17:54.070797375Z" level=info msg="Start streaming server" Sep 4 16:17:54.070844 containerd[2586]: time="2025-09-04T16:17:54.070807336Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 4 16:17:54.070844 containerd[2586]: time="2025-09-04T16:17:54.070815021Z" level=info msg="runtime interface starting up..." Sep 4 16:17:54.070844 containerd[2586]: time="2025-09-04T16:17:54.070822588Z" level=info msg="starting plugins..." Sep 4 16:17:54.070844 containerd[2586]: time="2025-09-04T16:17:54.070831566Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 4 16:17:54.071004 containerd[2586]: time="2025-09-04T16:17:54.070671121Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 16:17:54.071036 containerd[2586]: time="2025-09-04T16:17:54.071027757Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 16:17:54.071795 containerd[2586]: time="2025-09-04T16:17:54.071091661Z" level=info msg="containerd successfully booted in 0.061061s" Sep 4 16:17:54.071266 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 16:17:54.075230 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 4 16:17:54.087264 (dockerd)[2632]: 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 4 16:17:54.121716 dockerd[2632]: time="2025-09-04T16:17:54.121652031Z" level=info msg="Starting up" Sep 4 16:17:54.122413 dockerd[2632]: time="2025-09-04T16:17:54.122387812Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 4 16:17:54.135551 dockerd[2632]: time="2025-09-04T16:17:54.135510665Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 4 16:17:54.154060 dockerd[2632]: time="2025-09-04T16:17:54.154006893Z" level=info msg="[graphdriver] using prior storage driver: overlay2" Sep 4 16:17:54.177058 dockerd[2632]: time="2025-09-04T16:17:54.176994266Z" level=info msg="Loading containers: start." Sep 4 16:17:54.193785 systemd[1]: var-lib-docker-overlay2-255dceea7fe4df5cf18bccf486b629dd54d1900effd90ca361218801faf9d4e4-merged.mount: Deactivated successfully. Sep 4 16:17:54.669588 systemd-networkd[1457]: vethd9951c6: Link DOWN Sep 4 16:17:54.669601 systemd-networkd[1457]: vethd9951c6: Lost carrier Sep 4 16:17:54.671349 kernel: docker0: port 1(vethd9951c6) entered disabled state Sep 4 16:17:54.671399 kernel: vethd9951c6 (unregistering): left allmulticast mode Sep 4 16:17:54.671424 kernel: vethd9951c6 (unregistering): left promiscuous mode Sep 4 16:17:54.672903 kernel: docker0: port 1(vethd9951c6) entered disabled state Sep 4 16:17:54.675237 systemd-networkd[1457]: docker0: Lost carrier Sep 4 16:17:54.676273 dockerd[2632]: time="2025-09-04T16:17:54.676225538Z" level=info msg="Removing stale sandbox 8a77ae273487c69baf682f57fdf46bcd01d338e9f869f61f5ba6032451f7ef36 (6d2be36fce3415f35607cf887273ac9c2f9f12e720e8e44f5ea170a2ee17c2c7)" Sep 4 16:17:54.681737 dockerd[2632]: time="2025-09-04T16:17:54.681693319Z" level=warning msg="Failed deleting service host entries to the running container: open : no such file or directory" Sep 4 16:17:54.681737 dockerd[2632]: time="2025-09-04T16:17:54.681730557Z" level=warning msg="Error (Unable to complete atomic operation, key modified) deleting object [endpoint d95a6f1f6d5d39f980e3eaaae688950ab7f00a97d7452864fcbf4a68a85ea6b0 5b671032cc1b2fc11a98289a6167f9cbda4794ead357c676002d26478f1a75f1], retrying...." Sep 4 16:17:54.885750 dockerd[2632]: time="2025-09-04T16:17:54.885695817Z" level=warning msg="error locating sandbox id 8a77ae273487c69baf682f57fdf46bcd01d338e9f869f61f5ba6032451f7ef36: sandbox 8a77ae273487c69baf682f57fdf46bcd01d338e9f869f61f5ba6032451f7ef36 not found" Sep 4 16:17:54.885750 dockerd[2632]: time="2025-09-04T16:17:54.885731531Z" level=warning msg="error locating sandbox id 63fdb0eabea36612d60333411ceec42ecee7f326ac0954c8c678807a96c0141c: sandbox 63fdb0eabea36612d60333411ceec42ecee7f326ac0954c8c678807a96c0141c not found" Sep 4 16:17:54.885933 dockerd[2632]: time="2025-09-04T16:17:54.885852455Z" level=info msg="Loading containers: done." Sep 4 16:17:54.899469 dockerd[2632]: time="2025-09-04T16:17:54.899409207Z" 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 4 16:17:54.899681 dockerd[2632]: time="2025-09-04T16:17:54.899492471Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 4 16:17:54.899681 dockerd[2632]: time="2025-09-04T16:17:54.899524448Z" level=info msg="Initializing buildkit" Sep 4 16:17:54.916562 dockerd[2632]: time="2025-09-04T16:17:54.916503278Z" level=info msg="Completed buildkit initialization" Sep 4 16:17:54.920612 dockerd[2632]: time="2025-09-04T16:17:54.920473112Z" level=info msg="Daemon has completed initialization" Sep 4 16:17:54.920612 dockerd[2632]: time="2025-09-04T16:17:54.920524990Z" level=info msg="API listen on /run/docker.sock" Sep 4 16:17:54.920742 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 4 16:17:55.143050 systemd[1]: run-docker-netns-8a77ae273487.mount: Deactivated successfully. Sep 4 16:17:58.362865 systemd[1]: Started sshd@24-10.0.0.15:22-10.0.0.1:38124.service - OpenSSH per-connection server daemon (10.0.0.1:38124). Sep 4 16:17:58.424646 sshd[2869]: Accepted publickey for core from 10.0.0.1 port 38124 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:58.426237 sshd-session[2869]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:58.430726 systemd-logind[1523]: New session 25 of user core. Sep 4 16:17:58.446074 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 4 16:17:58.502570 sshd[2872]: Connection closed by 10.0.0.1 port 38124 Sep 4 16:17:58.502983 sshd-session[2869]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:58.514625 systemd[1]: sshd@24-10.0.0.15:22-10.0.0.1:38124.service: Deactivated successfully. Sep 4 16:17:58.516587 systemd[1]: session-25.scope: Deactivated successfully. Sep 4 16:17:58.517289 systemd-logind[1523]: Session 25 logged out. Waiting for processes to exit. Sep 4 16:17:58.520349 systemd[1]: Started sshd@25-10.0.0.15:22-10.0.0.1:38128.service - OpenSSH per-connection server daemon (10.0.0.1:38128). Sep 4 16:17:58.520913 systemd-logind[1523]: Removed session 25. Sep 4 16:17:58.578654 sshd[2878]: Accepted publickey for core from 10.0.0.1 port 38128 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:58.580013 sshd-session[2878]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:58.584323 systemd-logind[1523]: New session 26 of user core. Sep 4 16:17:58.594012 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 4 16:17:58.650399 sshd[2881]: Connection closed by 10.0.0.1 port 38128 Sep 4 16:17:58.651017 sshd-session[2878]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:58.666830 systemd[1]: sshd@25-10.0.0.15:22-10.0.0.1:38128.service: Deactivated successfully. Sep 4 16:17:58.668932 systemd[1]: session-26.scope: Deactivated successfully. Sep 4 16:17:58.669733 systemd-logind[1523]: Session 26 logged out. Waiting for processes to exit. Sep 4 16:17:58.672836 systemd[1]: Started sshd@26-10.0.0.15:22-10.0.0.1:38140.service - OpenSSH per-connection server daemon (10.0.0.1:38140). Sep 4 16:17:58.673676 systemd-logind[1523]: Removed session 26. Sep 4 16:17:58.738221 sshd[2887]: Accepted publickey for core from 10.0.0.1 port 38140 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:58.739961 sshd-session[2887]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:58.744467 systemd-logind[1523]: New session 27 of user core. Sep 4 16:17:58.763105 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 4 16:17:58.824965 sshd[2890]: Connection closed by 10.0.0.1 port 38140 Sep 4 16:17:58.825436 sshd-session[2887]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:58.842527 systemd[1]: sshd@26-10.0.0.15:22-10.0.0.1:38140.service: Deactivated successfully. Sep 4 16:17:58.844358 systemd[1]: session-27.scope: Deactivated successfully. Sep 4 16:17:58.845083 systemd-logind[1523]: Session 27 logged out. Waiting for processes to exit. Sep 4 16:17:58.847796 systemd[1]: Started sshd@27-10.0.0.15:22-10.0.0.1:38142.service - OpenSSH per-connection server daemon (10.0.0.1:38142). Sep 4 16:17:58.848352 systemd-logind[1523]: Removed session 27. Sep 4 16:17:58.907320 sshd[2903]: Accepted publickey for core from 10.0.0.1 port 38142 ssh2: RSA SHA256:Gi3V+rcn3j++vbR/HcfmcMqdfV/BOCBT7R1vPF/QTTY Sep 4 16:17:58.908550 sshd-session[2903]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 16:17:58.912748 systemd-logind[1523]: New session 28 of user core. Sep 4 16:17:58.926064 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 4 16:17:59.001800 systemd[1]: var-lib-docker-overlay2-5abe74378c872fc74d87712884cc7d7415aa8943cd7bc1c1f5a4704712394dc5\x2dinit-merged.mount: Deactivated successfully. Sep 4 16:17:59.020141 systemd[1]: var-lib-docker-overlay2-5abe74378c872fc74d87712884cc7d7415aa8943cd7bc1c1f5a4704712394dc5-merged.mount: Deactivated successfully. Sep 4 16:17:59.047907 containerd[2586]: time="2025-09-04T16:17:59.047643129Z" level=info msg="connecting to shim 62b28eb2896e0e4f8c7a2328949494f58eb6296d84744f90655382fbf2c557bd" address="unix:///run/containerd/s/d50362914a496a0809dfd6e3e8d6ceb5f0fa674ff5eb6760567b8a2a5c47bb83" namespace=moby protocol=ttrpc version=3 Sep 4 16:17:59.079020 systemd[1]: Started docker-62b28eb2896e0e4f8c7a2328949494f58eb6296d84744f90655382fbf2c557bd.scope - libcontainer container 62b28eb2896e0e4f8c7a2328949494f58eb6296d84744f90655382fbf2c557bd. Sep 4 16:17:59.093070 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 16:17:59.117305 kernel: docker0: port 1(veth1650fb5) entered blocking state Sep 4 16:17:59.117366 kernel: docker0: port 1(veth1650fb5) entered disabled state Sep 4 16:17:59.118133 kernel: veth1650fb5: entered allmulticast mode Sep 4 16:17:59.118904 kernel: veth1650fb5: entered promiscuous mode Sep 4 16:17:59.119663 systemd-networkd[1457]: veth1650fb5: Link UP Sep 4 16:17:59.127697 kernel: eth0: renamed from veth67e965a Sep 4 16:17:59.127753 kernel: docker0: port 1(veth1650fb5) entered blocking state Sep 4 16:17:59.127775 kernel: docker0: port 1(veth1650fb5) entered forwarding state Sep 4 16:17:59.131070 systemd-networkd[1457]: veth1650fb5: Gained carrier Sep 4 16:17:59.131305 systemd-networkd[1457]: docker0: Gained carrier Sep 4 16:17:59.154865 sshd[2907]: Connection closed by 10.0.0.1 port 38142 Sep 4 16:17:59.156091 sshd-session[2903]: pam_unix(sshd:session): session closed for user core Sep 4 16:17:59.160550 systemd[1]: sshd@27-10.0.0.15:22-10.0.0.1:38142.service: Deactivated successfully. Sep 4 16:17:59.162683 systemd[1]: session-28.scope: Deactivated successfully. Sep 4 16:17:59.164954 systemd-logind[1523]: Session 28 logged out. Waiting for processes to exit. Sep 4 16:17:59.165751 systemd-logind[1523]: Removed session 28. Sep 4 16:18:00.229147 systemd-networkd[1457]: veth1650fb5: Gained IPv6LL