Nov 1 09:59:24.671208 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Sat Nov 1 08:12:41 -00 2025 Nov 1 09:59:24.671269 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=91cbcb3658f876d239d31cc29b206c4e950f20e536a8e14bd58a23c6f0ecf128 Nov 1 09:59:24.671279 kernel: BIOS-provided physical RAM map: Nov 1 09:59:24.671290 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 1 09:59:24.671296 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Nov 1 09:59:24.671303 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Nov 1 09:59:24.671312 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Nov 1 09:59:24.671319 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Nov 1 09:59:24.671329 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable Nov 1 09:59:24.671336 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Nov 1 09:59:24.671346 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable Nov 1 09:59:24.671356 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Nov 1 09:59:24.671363 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable Nov 1 09:59:24.671370 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Nov 1 09:59:24.671380 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Nov 1 09:59:24.671387 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Nov 1 09:59:24.671400 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce90fff] usable Nov 1 09:59:24.671408 kernel: BIOS-e820: [mem 0x000000009ce91000-0x000000009ce94fff] reserved Nov 1 09:59:24.671416 kernel: BIOS-e820: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS Nov 1 09:59:24.671423 kernel: BIOS-e820: [mem 0x000000009ce97000-0x000000009cedbfff] usable Nov 1 09:59:24.671431 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Nov 1 09:59:24.671438 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Nov 1 09:59:24.671446 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Nov 1 09:59:24.671453 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 1 09:59:24.671461 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Nov 1 09:59:24.671468 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 1 09:59:24.671478 kernel: NX (Execute Disable) protection: active Nov 1 09:59:24.671488 kernel: APIC: Static calls initialized Nov 1 09:59:24.671496 kernel: e820: update [mem 0x9b320018-0x9b329c57] usable ==> usable Nov 1 09:59:24.671504 kernel: e820: update [mem 0x9b2e3018-0x9b31fe57] usable ==> usable Nov 1 09:59:24.671511 kernel: extended physical RAM map: Nov 1 09:59:24.671519 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 1 09:59:24.671526 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable Nov 1 09:59:24.671534 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Nov 1 09:59:24.671542 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable Nov 1 09:59:24.671549 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Nov 1 09:59:24.671557 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable Nov 1 09:59:24.671567 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Nov 1 09:59:24.671574 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b2e3017] usable Nov 1 09:59:24.671582 kernel: reserve setup_data: [mem 0x000000009b2e3018-0x000000009b31fe57] usable Nov 1 09:59:24.671593 kernel: reserve setup_data: [mem 0x000000009b31fe58-0x000000009b320017] usable Nov 1 09:59:24.671603 kernel: reserve setup_data: [mem 0x000000009b320018-0x000000009b329c57] usable Nov 1 09:59:24.671611 kernel: reserve setup_data: [mem 0x000000009b329c58-0x000000009bd3efff] usable Nov 1 09:59:24.671619 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Nov 1 09:59:24.671627 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable Nov 1 09:59:24.671634 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Nov 1 09:59:24.671642 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Nov 1 09:59:24.671650 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Nov 1 09:59:24.671658 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce90fff] usable Nov 1 09:59:24.671665 kernel: reserve setup_data: [mem 0x000000009ce91000-0x000000009ce94fff] reserved Nov 1 09:59:24.671675 kernel: reserve setup_data: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS Nov 1 09:59:24.671683 kernel: reserve setup_data: [mem 0x000000009ce97000-0x000000009cedbfff] usable Nov 1 09:59:24.671691 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Nov 1 09:59:24.671699 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Nov 1 09:59:24.671706 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Nov 1 09:59:24.671714 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 1 09:59:24.671722 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Nov 1 09:59:24.671729 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 1 09:59:24.671740 kernel: efi: EFI v2.7 by EDK II Nov 1 09:59:24.671748 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b9e4198 RNG=0x9cb73018 Nov 1 09:59:24.671756 kernel: random: crng init done Nov 1 09:59:24.671768 kernel: efi: Remove mem151: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Nov 1 09:59:24.671777 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Nov 1 09:59:24.671786 kernel: secureboot: Secure boot disabled Nov 1 09:59:24.671794 kernel: SMBIOS 2.8 present. Nov 1 09:59:24.671802 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Nov 1 09:59:24.671810 kernel: DMI: Memory slots populated: 1/1 Nov 1 09:59:24.671818 kernel: Hypervisor detected: KVM Nov 1 09:59:24.671825 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Nov 1 09:59:24.671833 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 1 09:59:24.671841 kernel: kvm-clock: using sched offset of 5142908417 cycles Nov 1 09:59:24.671849 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 1 09:59:24.671861 kernel: tsc: Detected 2794.748 MHz processor Nov 1 09:59:24.671869 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 1 09:59:24.671877 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 1 09:59:24.671886 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Nov 1 09:59:24.671894 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Nov 1 09:59:24.671902 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 1 09:59:24.671910 kernel: Using GB pages for direct mapping Nov 1 09:59:24.671921 kernel: ACPI: Early table checksum verification disabled Nov 1 09:59:24.671929 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Nov 1 09:59:24.671937 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Nov 1 09:59:24.671946 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 09:59:24.671954 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 09:59:24.671962 kernel: ACPI: FACS 0x000000009CBDD000 000040 Nov 1 09:59:24.671971 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 09:59:24.671981 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 09:59:24.671990 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 09:59:24.671998 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 09:59:24.672006 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Nov 1 09:59:24.672014 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Nov 1 09:59:24.672022 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Nov 1 09:59:24.672031 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Nov 1 09:59:24.672038 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Nov 1 09:59:24.672049 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Nov 1 09:59:24.672057 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Nov 1 09:59:24.672065 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Nov 1 09:59:24.672073 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Nov 1 09:59:24.672081 kernel: No NUMA configuration found Nov 1 09:59:24.672090 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] Nov 1 09:59:24.672098 kernel: NODE_DATA(0) allocated [mem 0x9ce36dc0-0x9ce3dfff] Nov 1 09:59:24.672108 kernel: Zone ranges: Nov 1 09:59:24.672116 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 1 09:59:24.672124 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] Nov 1 09:59:24.672132 kernel: Normal empty Nov 1 09:59:24.672140 kernel: Device empty Nov 1 09:59:24.672148 kernel: Movable zone start for each node Nov 1 09:59:24.672156 kernel: Early memory node ranges Nov 1 09:59:24.672164 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 1 09:59:24.672177 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Nov 1 09:59:24.672185 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Nov 1 09:59:24.672193 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] Nov 1 09:59:24.672202 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] Nov 1 09:59:24.672210 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] Nov 1 09:59:24.672218 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce90fff] Nov 1 09:59:24.672226 kernel: node 0: [mem 0x000000009ce97000-0x000000009cedbfff] Nov 1 09:59:24.672250 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] Nov 1 09:59:24.672267 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 1 09:59:24.672283 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 1 09:59:24.672294 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Nov 1 09:59:24.672302 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 1 09:59:24.672311 kernel: On node 0, zone DMA: 239 pages in unavailable ranges Nov 1 09:59:24.672319 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Nov 1 09:59:24.672327 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Nov 1 09:59:24.672336 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Nov 1 09:59:24.672344 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges Nov 1 09:59:24.672355 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 1 09:59:24.672364 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 1 09:59:24.672372 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 1 09:59:24.672380 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 1 09:59:24.672395 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 1 09:59:24.672404 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 1 09:59:24.672412 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 1 09:59:24.672421 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 1 09:59:24.672429 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 1 09:59:24.672437 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 1 09:59:24.672446 kernel: TSC deadline timer available Nov 1 09:59:24.672456 kernel: CPU topo: Max. logical packages: 1 Nov 1 09:59:24.672465 kernel: CPU topo: Max. logical dies: 1 Nov 1 09:59:24.672473 kernel: CPU topo: Max. dies per package: 1 Nov 1 09:59:24.672481 kernel: CPU topo: Max. threads per core: 1 Nov 1 09:59:24.672490 kernel: CPU topo: Num. cores per package: 4 Nov 1 09:59:24.672498 kernel: CPU topo: Num. threads per package: 4 Nov 1 09:59:24.672506 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Nov 1 09:59:24.672517 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 1 09:59:24.672525 kernel: kvm-guest: KVM setup pv remote TLB flush Nov 1 09:59:24.672534 kernel: kvm-guest: setup PV sched yield Nov 1 09:59:24.672542 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Nov 1 09:59:24.672551 kernel: Booting paravirtualized kernel on KVM Nov 1 09:59:24.672559 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 1 09:59:24.672568 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Nov 1 09:59:24.672576 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Nov 1 09:59:24.672587 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Nov 1 09:59:24.672596 kernel: pcpu-alloc: [0] 0 1 2 3 Nov 1 09:59:24.672604 kernel: kvm-guest: PV spinlocks enabled Nov 1 09:59:24.672613 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 1 09:59:24.672625 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=91cbcb3658f876d239d31cc29b206c4e950f20e536a8e14bd58a23c6f0ecf128 Nov 1 09:59:24.672634 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 1 09:59:24.672645 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 1 09:59:24.672654 kernel: Fallback order for Node 0: 0 Nov 1 09:59:24.672662 kernel: Built 1 zonelists, mobility grouping on. Total pages: 641450 Nov 1 09:59:24.672671 kernel: Policy zone: DMA32 Nov 1 09:59:24.672679 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 1 09:59:24.672688 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 1 09:59:24.672696 kernel: ftrace: allocating 40092 entries in 157 pages Nov 1 09:59:24.672707 kernel: ftrace: allocated 157 pages with 5 groups Nov 1 09:59:24.672715 kernel: Dynamic Preempt: voluntary Nov 1 09:59:24.672723 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 1 09:59:24.672733 kernel: rcu: RCU event tracing is enabled. Nov 1 09:59:24.672742 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 1 09:59:24.672750 kernel: Trampoline variant of Tasks RCU enabled. Nov 1 09:59:24.672758 kernel: Rude variant of Tasks RCU enabled. Nov 1 09:59:24.672767 kernel: Tracing variant of Tasks RCU enabled. Nov 1 09:59:24.672778 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 1 09:59:24.672786 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 1 09:59:24.672798 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 1 09:59:24.672807 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 1 09:59:24.672815 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 1 09:59:24.672824 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Nov 1 09:59:24.672832 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 1 09:59:24.672846 kernel: Console: colour dummy device 80x25 Nov 1 09:59:24.672854 kernel: printk: legacy console [ttyS0] enabled Nov 1 09:59:24.672862 kernel: ACPI: Core revision 20240827 Nov 1 09:59:24.672871 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 1 09:59:24.672880 kernel: APIC: Switch to symmetric I/O mode setup Nov 1 09:59:24.672888 kernel: x2apic enabled Nov 1 09:59:24.672896 kernel: APIC: Switched APIC routing to: physical x2apic Nov 1 09:59:24.672908 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Nov 1 09:59:24.672917 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Nov 1 09:59:24.672925 kernel: kvm-guest: setup PV IPIs Nov 1 09:59:24.672933 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 1 09:59:24.672942 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Nov 1 09:59:24.672950 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Nov 1 09:59:24.672959 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Nov 1 09:59:24.672969 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Nov 1 09:59:24.672978 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Nov 1 09:59:24.672986 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 1 09:59:24.672995 kernel: Spectre V2 : Mitigation: Retpolines Nov 1 09:59:24.673003 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 1 09:59:24.673011 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Nov 1 09:59:24.673020 kernel: active return thunk: retbleed_return_thunk Nov 1 09:59:24.673031 kernel: RETBleed: Mitigation: untrained return thunk Nov 1 09:59:24.673042 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Nov 1 09:59:24.673050 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Nov 1 09:59:24.673059 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Nov 1 09:59:24.673068 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Nov 1 09:59:24.673077 kernel: active return thunk: srso_return_thunk Nov 1 09:59:24.673088 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Nov 1 09:59:24.673096 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 1 09:59:24.673105 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 1 09:59:24.673113 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 1 09:59:24.673121 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 1 09:59:24.673130 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Nov 1 09:59:24.673138 kernel: Freeing SMP alternatives memory: 32K Nov 1 09:59:24.673149 kernel: pid_max: default: 32768 minimum: 301 Nov 1 09:59:24.673157 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 1 09:59:24.673166 kernel: landlock: Up and running. Nov 1 09:59:24.673174 kernel: SELinux: Initializing. Nov 1 09:59:24.673183 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 1 09:59:24.673191 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 1 09:59:24.673200 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Nov 1 09:59:24.673208 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Nov 1 09:59:24.673219 kernel: ... version: 0 Nov 1 09:59:24.673227 kernel: ... bit width: 48 Nov 1 09:59:24.673246 kernel: ... generic registers: 6 Nov 1 09:59:24.673254 kernel: ... value mask: 0000ffffffffffff Nov 1 09:59:24.673271 kernel: ... max period: 00007fffffffffff Nov 1 09:59:24.673280 kernel: ... fixed-purpose events: 0 Nov 1 09:59:24.673291 kernel: ... event mask: 000000000000003f Nov 1 09:59:24.673303 kernel: signal: max sigframe size: 1776 Nov 1 09:59:24.673313 kernel: rcu: Hierarchical SRCU implementation. Nov 1 09:59:24.673321 kernel: rcu: Max phase no-delay instances is 400. Nov 1 09:59:24.673335 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 1 09:59:24.673343 kernel: smp: Bringing up secondary CPUs ... Nov 1 09:59:24.673352 kernel: smpboot: x86: Booting SMP configuration: Nov 1 09:59:24.673360 kernel: .... node #0, CPUs: #1 #2 #3 Nov 1 09:59:24.673371 kernel: smp: Brought up 1 node, 4 CPUs Nov 1 09:59:24.673380 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Nov 1 09:59:24.673389 kernel: Memory: 2441100K/2565800K available (14336K kernel code, 2443K rwdata, 29892K rodata, 15356K init, 2688K bss, 118764K reserved, 0K cma-reserved) Nov 1 09:59:24.673397 kernel: devtmpfs: initialized Nov 1 09:59:24.673406 kernel: x86/mm: Memory block size: 128MB Nov 1 09:59:24.673414 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Nov 1 09:59:24.673422 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Nov 1 09:59:24.673433 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) Nov 1 09:59:24.673442 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Nov 1 09:59:24.673450 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce95000-0x9ce96fff] (8192 bytes) Nov 1 09:59:24.673459 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Nov 1 09:59:24.673467 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 1 09:59:24.673476 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 1 09:59:24.673487 kernel: pinctrl core: initialized pinctrl subsystem Nov 1 09:59:24.673499 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 1 09:59:24.673507 kernel: audit: initializing netlink subsys (disabled) Nov 1 09:59:24.673516 kernel: audit: type=2000 audit(1761991160.690:1): state=initialized audit_enabled=0 res=1 Nov 1 09:59:24.673524 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 1 09:59:24.673533 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 1 09:59:24.673541 kernel: cpuidle: using governor menu Nov 1 09:59:24.673550 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 1 09:59:24.673560 kernel: dca service started, version 1.12.1 Nov 1 09:59:24.673569 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Nov 1 09:59:24.673577 kernel: PCI: Using configuration type 1 for base access Nov 1 09:59:24.673586 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 1 09:59:24.673595 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 1 09:59:24.673603 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 1 09:59:24.673611 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 1 09:59:24.673622 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 1 09:59:24.673631 kernel: ACPI: Added _OSI(Module Device) Nov 1 09:59:24.673639 kernel: ACPI: Added _OSI(Processor Device) Nov 1 09:59:24.673647 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 1 09:59:24.673656 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 1 09:59:24.673664 kernel: ACPI: Interpreter enabled Nov 1 09:59:24.673673 kernel: ACPI: PM: (supports S0 S3 S5) Nov 1 09:59:24.673683 kernel: ACPI: Using IOAPIC for interrupt routing Nov 1 09:59:24.673692 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 1 09:59:24.673701 kernel: PCI: Using E820 reservations for host bridge windows Nov 1 09:59:24.673709 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Nov 1 09:59:24.673717 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 1 09:59:24.674017 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 1 09:59:24.674201 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Nov 1 09:59:24.674407 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Nov 1 09:59:24.674420 kernel: PCI host bridge to bus 0000:00 Nov 1 09:59:24.674625 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 1 09:59:24.674789 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 1 09:59:24.674949 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 1 09:59:24.675112 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Nov 1 09:59:24.675296 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Nov 1 09:59:24.675459 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Nov 1 09:59:24.675619 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 1 09:59:24.675836 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Nov 1 09:59:24.676039 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Nov 1 09:59:24.676219 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Nov 1 09:59:24.676454 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Nov 1 09:59:24.676627 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Nov 1 09:59:24.676799 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 1 09:59:24.676998 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 1 09:59:24.677181 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Nov 1 09:59:24.677385 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Nov 1 09:59:24.677567 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref] Nov 1 09:59:24.677765 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Nov 1 09:59:24.677943 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Nov 1 09:59:24.678119 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Nov 1 09:59:24.678329 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref] Nov 1 09:59:24.678535 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Nov 1 09:59:24.678714 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Nov 1 09:59:24.678889 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Nov 1 09:59:24.679063 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref] Nov 1 09:59:24.679253 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Nov 1 09:59:24.679469 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Nov 1 09:59:24.679643 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Nov 1 09:59:24.679899 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Nov 1 09:59:24.680075 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Nov 1 09:59:24.680277 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Nov 1 09:59:24.680482 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Nov 1 09:59:24.680655 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Nov 1 09:59:24.680667 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 1 09:59:24.680676 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 1 09:59:24.680685 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 1 09:59:24.680693 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 1 09:59:24.680705 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Nov 1 09:59:24.680714 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Nov 1 09:59:24.680722 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Nov 1 09:59:24.680731 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Nov 1 09:59:24.680739 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Nov 1 09:59:24.680747 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Nov 1 09:59:24.680756 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Nov 1 09:59:24.680764 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Nov 1 09:59:24.680775 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Nov 1 09:59:24.680783 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Nov 1 09:59:24.680792 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Nov 1 09:59:24.680800 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Nov 1 09:59:24.680809 kernel: iommu: Default domain type: Translated Nov 1 09:59:24.680817 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 1 09:59:24.680826 kernel: efivars: Registered efivars operations Nov 1 09:59:24.680836 kernel: PCI: Using ACPI for IRQ routing Nov 1 09:59:24.680845 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 1 09:59:24.680853 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Nov 1 09:59:24.680862 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] Nov 1 09:59:24.680870 kernel: e820: reserve RAM buffer [mem 0x9b2e3018-0x9bffffff] Nov 1 09:59:24.680879 kernel: e820: reserve RAM buffer [mem 0x9b320018-0x9bffffff] Nov 1 09:59:24.680887 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] Nov 1 09:59:24.680897 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] Nov 1 09:59:24.680906 kernel: e820: reserve RAM buffer [mem 0x9ce91000-0x9fffffff] Nov 1 09:59:24.680914 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] Nov 1 09:59:24.681092 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Nov 1 09:59:24.681286 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Nov 1 09:59:24.681461 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 1 09:59:24.681478 kernel: vgaarb: loaded Nov 1 09:59:24.681486 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 1 09:59:24.681495 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 1 09:59:24.681503 kernel: clocksource: Switched to clocksource kvm-clock Nov 1 09:59:24.681516 kernel: VFS: Disk quotas dquot_6.6.0 Nov 1 09:59:24.681525 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 1 09:59:24.681533 kernel: pnp: PnP ACPI init Nov 1 09:59:24.681766 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Nov 1 09:59:24.681783 kernel: pnp: PnP ACPI: found 6 devices Nov 1 09:59:24.681792 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 1 09:59:24.681801 kernel: NET: Registered PF_INET protocol family Nov 1 09:59:24.681810 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 1 09:59:24.681819 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 1 09:59:24.681828 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 1 09:59:24.681839 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 1 09:59:24.681848 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 1 09:59:24.681857 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 1 09:59:24.681866 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 1 09:59:24.681875 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 1 09:59:24.681884 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 1 09:59:24.681893 kernel: NET: Registered PF_XDP protocol family Nov 1 09:59:24.682070 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Nov 1 09:59:24.682269 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Nov 1 09:59:24.682436 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 1 09:59:24.682596 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 1 09:59:24.682757 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 1 09:59:24.682919 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Nov 1 09:59:24.683084 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Nov 1 09:59:24.683290 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Nov 1 09:59:24.683304 kernel: PCI: CLS 0 bytes, default 64 Nov 1 09:59:24.683313 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Nov 1 09:59:24.683328 kernel: Initialise system trusted keyrings Nov 1 09:59:24.683337 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 1 09:59:24.683346 kernel: Key type asymmetric registered Nov 1 09:59:24.683355 kernel: Asymmetric key parser 'x509' registered Nov 1 09:59:24.683363 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 1 09:59:24.683372 kernel: io scheduler mq-deadline registered Nov 1 09:59:24.683381 kernel: io scheduler kyber registered Nov 1 09:59:24.683392 kernel: io scheduler bfq registered Nov 1 09:59:24.683401 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 1 09:59:24.683410 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Nov 1 09:59:24.683419 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Nov 1 09:59:24.683428 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Nov 1 09:59:24.683436 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 1 09:59:24.683448 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 1 09:59:24.683458 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 1 09:59:24.683469 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 1 09:59:24.683478 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 1 09:59:24.683487 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Nov 1 09:59:24.683677 kernel: rtc_cmos 00:04: RTC can wake from S4 Nov 1 09:59:24.683848 kernel: rtc_cmos 00:04: registered as rtc0 Nov 1 09:59:24.684016 kernel: rtc_cmos 00:04: setting system clock to 2025-11-01T09:59:22 UTC (1761991162) Nov 1 09:59:24.684190 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Nov 1 09:59:24.684202 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Nov 1 09:59:24.684211 kernel: efifb: probing for efifb Nov 1 09:59:24.684220 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Nov 1 09:59:24.684243 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Nov 1 09:59:24.684252 kernel: efifb: scrolling: redraw Nov 1 09:59:24.684269 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 1 09:59:24.684282 kernel: Console: switching to colour frame buffer device 160x50 Nov 1 09:59:24.684291 kernel: fb0: EFI VGA frame buffer device Nov 1 09:59:24.684300 kernel: pstore: Using crash dump compression: deflate Nov 1 09:59:24.684308 kernel: pstore: Registered efi_pstore as persistent store backend Nov 1 09:59:24.684320 kernel: NET: Registered PF_INET6 protocol family Nov 1 09:59:24.684328 kernel: Segment Routing with IPv6 Nov 1 09:59:24.684337 kernel: In-situ OAM (IOAM) with IPv6 Nov 1 09:59:24.684352 kernel: NET: Registered PF_PACKET protocol family Nov 1 09:59:24.684361 kernel: Key type dns_resolver registered Nov 1 09:59:24.684369 kernel: IPI shorthand broadcast: enabled Nov 1 09:59:24.684378 kernel: sched_clock: Marking stable (2220002393, 300205716)->(2580365797, -60157688) Nov 1 09:59:24.684387 kernel: registered taskstats version 1 Nov 1 09:59:24.684398 kernel: Loading compiled-in X.509 certificates Nov 1 09:59:24.684407 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: d8ad6d63e9d0f6e32055e659cacaf9092255a45e' Nov 1 09:59:24.684418 kernel: Demotion targets for Node 0: null Nov 1 09:59:24.684427 kernel: Key type .fscrypt registered Nov 1 09:59:24.684435 kernel: Key type fscrypt-provisioning registered Nov 1 09:59:24.684444 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 1 09:59:24.684453 kernel: ima: Allocated hash algorithm: sha1 Nov 1 09:59:24.684461 kernel: ima: No architecture policies found Nov 1 09:59:24.684470 kernel: clk: Disabling unused clocks Nov 1 09:59:24.684479 kernel: Freeing unused kernel image (initmem) memory: 15356K Nov 1 09:59:24.684490 kernel: Write protecting the kernel read-only data: 45056k Nov 1 09:59:24.684499 kernel: Freeing unused kernel image (rodata/data gap) memory: 828K Nov 1 09:59:24.684507 kernel: Run /init as init process Nov 1 09:59:24.684524 kernel: with arguments: Nov 1 09:59:24.684533 kernel: /init Nov 1 09:59:24.684542 kernel: with environment: Nov 1 09:59:24.684551 kernel: HOME=/ Nov 1 09:59:24.684562 kernel: TERM=linux Nov 1 09:59:24.684577 kernel: SCSI subsystem initialized Nov 1 09:59:24.684589 kernel: libata version 3.00 loaded. Nov 1 09:59:24.684779 kernel: ahci 0000:00:1f.2: version 3.0 Nov 1 09:59:24.684793 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Nov 1 09:59:24.685018 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Nov 1 09:59:24.685194 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Nov 1 09:59:24.685404 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Nov 1 09:59:24.685624 kernel: scsi host0: ahci Nov 1 09:59:24.685824 kernel: scsi host1: ahci Nov 1 09:59:24.686025 kernel: scsi host2: ahci Nov 1 09:59:24.686247 kernel: scsi host3: ahci Nov 1 09:59:24.686496 kernel: scsi host4: ahci Nov 1 09:59:24.686713 kernel: scsi host5: ahci Nov 1 09:59:24.686727 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 26 lpm-pol 1 Nov 1 09:59:24.686737 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 26 lpm-pol 1 Nov 1 09:59:24.686745 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 26 lpm-pol 1 Nov 1 09:59:24.686755 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 26 lpm-pol 1 Nov 1 09:59:24.686768 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 26 lpm-pol 1 Nov 1 09:59:24.686777 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 26 lpm-pol 1 Nov 1 09:59:24.686786 kernel: ata5: SATA link down (SStatus 0 SControl 300) Nov 1 09:59:24.686795 kernel: ata2: SATA link down (SStatus 0 SControl 300) Nov 1 09:59:24.686804 kernel: ata1: SATA link down (SStatus 0 SControl 300) Nov 1 09:59:24.686812 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Nov 1 09:59:24.686821 kernel: ata4: SATA link down (SStatus 0 SControl 300) Nov 1 09:59:24.686832 kernel: ata6: SATA link down (SStatus 0 SControl 300) Nov 1 09:59:24.686841 kernel: ata3.00: LPM support broken, forcing max_power Nov 1 09:59:24.686849 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Nov 1 09:59:24.686858 kernel: ata3.00: applying bridge limits Nov 1 09:59:24.686867 kernel: ata3.00: LPM support broken, forcing max_power Nov 1 09:59:24.686875 kernel: ata3.00: configured for UDMA/100 Nov 1 09:59:24.687100 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Nov 1 09:59:24.687356 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Nov 1 09:59:24.687536 kernel: virtio_blk virtio1: [vda] 16515072 512-byte logical blocks (8.46 GB/7.88 GiB) Nov 1 09:59:24.687549 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 1 09:59:24.687739 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Nov 1 09:59:24.687752 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 1 09:59:24.687941 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Nov 1 09:59:24.687959 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 1 09:59:24.687968 kernel: device-mapper: uevent: version 1.0.3 Nov 1 09:59:24.687977 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 1 09:59:24.687986 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 1 09:59:24.687997 kernel: raid6: avx2x4 gen() 24801 MB/s Nov 1 09:59:24.688025 kernel: raid6: avx2x2 gen() 30514 MB/s Nov 1 09:59:24.688035 kernel: raid6: avx2x1 gen() 25303 MB/s Nov 1 09:59:24.688047 kernel: raid6: using algorithm avx2x2 gen() 30514 MB/s Nov 1 09:59:24.688056 kernel: raid6: .... xor() 19743 MB/s, rmw enabled Nov 1 09:59:24.688065 kernel: raid6: using avx2x2 recovery algorithm Nov 1 09:59:24.688073 kernel: xor: automatically using best checksumming function avx Nov 1 09:59:24.688082 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 1 09:59:24.688091 kernel: BTRFS: device fsid 8763e8a0-bf7f-4ffe-acc8-da149b03dd0b devid 1 transid 35 /dev/mapper/usr (253:0) scanned by mount (182) Nov 1 09:59:24.688101 kernel: BTRFS info (device dm-0): first mount of filesystem 8763e8a0-bf7f-4ffe-acc8-da149b03dd0b Nov 1 09:59:24.688113 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 1 09:59:24.688122 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 1 09:59:24.688131 kernel: BTRFS info (device dm-0): enabling free space tree Nov 1 09:59:24.688140 kernel: loop: module loaded Nov 1 09:59:24.688149 kernel: loop0: detected capacity change from 0 to 100136 Nov 1 09:59:24.688157 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 1 09:59:24.688167 systemd[1]: Successfully made /usr/ read-only. Nov 1 09:59:24.688181 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) Nov 1 09:59:24.688191 systemd[1]: Detected virtualization kvm. Nov 1 09:59:24.688212 systemd[1]: Detected architecture x86-64. Nov 1 09:59:24.688222 systemd[1]: Running in initrd. Nov 1 09:59:24.688244 systemd[1]: No hostname configured, using default hostname. Nov 1 09:59:24.688268 systemd[1]: Hostname set to . Nov 1 09:59:24.688277 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 1 09:59:24.688287 systemd[1]: Queued start job for default target initrd.target. Nov 1 09:59:24.688296 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 1 09:59:24.688306 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 1 09:59:24.688315 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 1 09:59:24.688325 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 1 09:59:24.688340 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 1 09:59:24.688350 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 1 09:59:24.688360 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 1 09:59:24.688369 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 1 09:59:24.688379 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 1 09:59:24.688390 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 1 09:59:24.688400 systemd[1]: Reached target paths.target - Path Units. Nov 1 09:59:24.688409 systemd[1]: Reached target slices.target - Slice Units. Nov 1 09:59:24.688418 systemd[1]: Reached target swap.target - Swaps. Nov 1 09:59:24.688434 systemd[1]: Reached target timers.target - Timer Units. Nov 1 09:59:24.688447 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 1 09:59:24.688456 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 1 09:59:24.688470 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 1 09:59:24.688479 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 1 09:59:24.688488 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 1 09:59:24.688498 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 1 09:59:24.688507 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 1 09:59:24.688516 systemd[1]: Reached target sockets.target - Socket Units. Nov 1 09:59:24.688526 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 1 09:59:24.688537 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 1 09:59:24.688547 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 1 09:59:24.688556 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 1 09:59:24.688566 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). Nov 1 09:59:24.688576 systemd[1]: Starting systemd-fsck-usr.service... Nov 1 09:59:24.688585 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 1 09:59:24.688594 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 1 09:59:24.688606 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 09:59:24.688616 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 1 09:59:24.688625 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 1 09:59:24.688637 systemd[1]: Finished systemd-fsck-usr.service. Nov 1 09:59:24.688649 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 1 09:59:24.688693 systemd-journald[316]: Collecting audit messages is disabled. Nov 1 09:59:24.688717 systemd-journald[316]: Journal started Nov 1 09:59:24.688739 systemd-journald[316]: Runtime Journal (/run/log/journal/1406e6af5093484a8b02a3517c282912) is 6M, max 48.1M, 42M free. Nov 1 09:59:24.692484 systemd[1]: Started systemd-journald.service - Journal Service. Nov 1 09:59:24.800820 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 1 09:59:24.809251 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 1 09:59:24.812176 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 09:59:24.816948 kernel: Bridge firewalling registered Nov 1 09:59:24.812959 systemd-modules-load[318]: Inserted module 'br_netfilter' Nov 1 09:59:24.814714 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 1 09:59:24.822929 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 1 09:59:24.827648 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 1 09:59:24.836980 systemd-tmpfiles[332]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 1 09:59:24.840173 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 1 09:59:24.845339 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 1 09:59:24.849320 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 1 09:59:24.853294 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 1 09:59:24.863752 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 1 09:59:24.865884 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 1 09:59:24.878401 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 1 09:59:24.882040 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 1 09:59:24.911751 dracut-cmdline[358]: 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=91cbcb3658f876d239d31cc29b206c4e950f20e536a8e14bd58a23c6f0ecf128 Nov 1 09:59:24.937652 systemd-resolved[357]: Positive Trust Anchors: Nov 1 09:59:24.937666 systemd-resolved[357]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 1 09:59:24.937670 systemd-resolved[357]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 1 09:59:24.937702 systemd-resolved[357]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 1 09:59:24.970455 systemd-resolved[357]: Defaulting to hostname 'linux'. Nov 1 09:59:24.972409 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 1 09:59:24.976324 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 1 09:59:25.063275 kernel: Loading iSCSI transport class v2.0-870. Nov 1 09:59:25.078269 kernel: iscsi: registered transport (tcp) Nov 1 09:59:25.102842 kernel: iscsi: registered transport (qla4xxx) Nov 1 09:59:25.102941 kernel: QLogic iSCSI HBA Driver Nov 1 09:59:25.133271 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 1 09:59:25.171801 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 1 09:59:25.177171 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 1 09:59:25.240918 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 1 09:59:25.243270 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 1 09:59:25.245461 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 1 09:59:25.296582 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 1 09:59:25.299973 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 1 09:59:25.335746 systemd-udevd[594]: Using default interface naming scheme 'v257'. Nov 1 09:59:25.352552 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 1 09:59:25.357907 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 1 09:59:25.387650 dracut-pre-trigger[658]: rd.md=0: removing MD RAID activation Nov 1 09:59:25.402069 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 1 09:59:25.406935 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 1 09:59:25.425913 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 1 09:59:25.430842 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 1 09:59:25.467160 systemd-networkd[721]: lo: Link UP Nov 1 09:59:25.467169 systemd-networkd[721]: lo: Gained carrier Nov 1 09:59:25.467834 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 1 09:59:25.470377 systemd[1]: Reached target network.target - Network. Nov 1 09:59:25.528604 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 1 09:59:25.534696 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 1 09:59:25.591759 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 1 09:59:25.604694 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 1 09:59:25.628603 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 1 09:59:25.633820 kernel: cryptd: max_cpu_qlen set to 1000 Nov 1 09:59:25.644674 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 1 09:59:25.650459 kernel: AES CTR mode by8 optimization enabled Nov 1 09:59:25.652342 systemd-networkd[721]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 1 09:59:25.652354 systemd-networkd[721]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 1 09:59:25.653182 systemd-networkd[721]: eth0: Link UP Nov 1 09:59:25.653538 systemd-networkd[721]: eth0: Gained carrier Nov 1 09:59:25.653548 systemd-networkd[721]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 1 09:59:25.681846 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Nov 1 09:59:25.662040 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 1 09:59:25.666013 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 1 09:59:25.668297 systemd-networkd[721]: eth0: DHCPv4 address 10.0.0.18/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 1 09:59:25.668345 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 09:59:25.672059 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 09:59:25.683533 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 09:59:25.743967 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 09:59:25.755179 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 1 09:59:25.758674 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 1 09:59:25.764495 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 1 09:59:25.767600 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 1 09:59:25.770655 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 1 09:59:25.807549 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 1 09:59:25.976971 systemd-resolved[357]: Detected conflict on linux IN A 10.0.0.18 Nov 1 09:59:25.976988 systemd-resolved[357]: Hostname conflict, changing published hostname from 'linux' to 'linux2'. Nov 1 09:59:26.751475 disk-uuid[825]: Warning: The kernel is still using the old partition table. Nov 1 09:59:26.751475 disk-uuid[825]: The new table will be used at the next reboot or after you Nov 1 09:59:26.751475 disk-uuid[825]: run partprobe(8) or kpartx(8) Nov 1 09:59:26.751475 disk-uuid[825]: The operation has completed successfully. Nov 1 09:59:26.764120 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 1 09:59:26.764305 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 1 09:59:26.766511 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 1 09:59:26.809279 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (864) Nov 1 09:59:26.812562 kernel: BTRFS info (device vda6): first mount of filesystem 75c18d9e-3deb-43e1-a433-af20f45ab517 Nov 1 09:59:26.812591 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 1 09:59:26.816166 kernel: BTRFS info (device vda6): turning on async discard Nov 1 09:59:26.816208 kernel: BTRFS info (device vda6): enabling free space tree Nov 1 09:59:26.823270 kernel: BTRFS info (device vda6): last unmount of filesystem 75c18d9e-3deb-43e1-a433-af20f45ab517 Nov 1 09:59:26.824684 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 1 09:59:26.827793 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 1 09:59:27.090760 ignition[883]: Ignition 2.22.0 Nov 1 09:59:27.092119 ignition[883]: Stage: fetch-offline Nov 1 09:59:27.092257 ignition[883]: no configs at "/usr/lib/ignition/base.d" Nov 1 09:59:27.092275 ignition[883]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 1 09:59:27.092435 ignition[883]: parsed url from cmdline: "" Nov 1 09:59:27.092439 ignition[883]: no config URL provided Nov 1 09:59:27.092445 ignition[883]: reading system config file "/usr/lib/ignition/user.ign" Nov 1 09:59:27.092457 ignition[883]: no config at "/usr/lib/ignition/user.ign" Nov 1 09:59:27.092519 ignition[883]: op(1): [started] loading QEMU firmware config module Nov 1 09:59:27.092524 ignition[883]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 1 09:59:27.104013 ignition[883]: op(1): [finished] loading QEMU firmware config module Nov 1 09:59:27.105874 ignition[883]: parsing config with SHA512: d31759548b51ec14c73b8d5cf00863b5538acba3a7d7457bbc116253686c5dc169e0da20dfefbdfa00c88c4e3d7692f067718e0043fbe3335038b70e5a216289 Nov 1 09:59:27.115006 unknown[883]: fetched base config from "system" Nov 1 09:59:27.115022 unknown[883]: fetched user config from "qemu" Nov 1 09:59:27.115297 ignition[883]: fetch-offline: fetch-offline passed Nov 1 09:59:27.115398 ignition[883]: Ignition finished successfully Nov 1 09:59:27.123680 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 1 09:59:27.124937 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 1 09:59:27.131105 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 1 09:59:27.209094 ignition[894]: Ignition 2.22.0 Nov 1 09:59:27.209108 ignition[894]: Stage: kargs Nov 1 09:59:27.209280 ignition[894]: no configs at "/usr/lib/ignition/base.d" Nov 1 09:59:27.209291 ignition[894]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 1 09:59:27.209816 ignition[894]: kargs: kargs passed Nov 1 09:59:27.209864 ignition[894]: Ignition finished successfully Nov 1 09:59:27.221146 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 1 09:59:27.223475 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 1 09:59:27.336190 ignition[902]: Ignition 2.22.0 Nov 1 09:59:27.336206 ignition[902]: Stage: disks Nov 1 09:59:27.336405 ignition[902]: no configs at "/usr/lib/ignition/base.d" Nov 1 09:59:27.336416 ignition[902]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 1 09:59:27.337257 ignition[902]: disks: disks passed Nov 1 09:59:27.337307 ignition[902]: Ignition finished successfully Nov 1 09:59:27.347099 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 1 09:59:27.350646 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 1 09:59:27.351688 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 1 09:59:27.355010 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 1 09:59:27.361541 systemd[1]: Reached target sysinit.target - System Initialization. Nov 1 09:59:27.362276 systemd[1]: Reached target basic.target - Basic System. Nov 1 09:59:27.368007 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 1 09:59:27.417364 systemd-fsck[912]: ROOT: clean, 15/456736 files, 38230/456704 blocks Nov 1 09:59:27.425688 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 1 09:59:27.428260 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 1 09:59:27.544451 systemd-networkd[721]: eth0: Gained IPv6LL Nov 1 09:59:27.570281 kernel: EXT4-fs (vda9): mounted filesystem 9a0b584a-8c68-48a6-a0f9-92613ad0f15d r/w with ordered data mode. Quota mode: none. Nov 1 09:59:27.570781 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 1 09:59:27.574026 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 1 09:59:27.579314 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 1 09:59:27.582913 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 1 09:59:27.585841 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 1 09:59:27.585883 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 1 09:59:27.585908 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 1 09:59:27.597682 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 1 09:59:27.606876 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (920) Nov 1 09:59:27.606901 kernel: BTRFS info (device vda6): first mount of filesystem 75c18d9e-3deb-43e1-a433-af20f45ab517 Nov 1 09:59:27.606913 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 1 09:59:27.606924 kernel: BTRFS info (device vda6): turning on async discard Nov 1 09:59:27.606941 kernel: BTRFS info (device vda6): enabling free space tree Nov 1 09:59:27.607933 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 1 09:59:27.612623 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 1 09:59:27.688974 initrd-setup-root[944]: cut: /sysroot/etc/passwd: No such file or directory Nov 1 09:59:27.696062 initrd-setup-root[951]: cut: /sysroot/etc/group: No such file or directory Nov 1 09:59:27.700737 initrd-setup-root[958]: cut: /sysroot/etc/shadow: No such file or directory Nov 1 09:59:27.705699 initrd-setup-root[965]: cut: /sysroot/etc/gshadow: No such file or directory Nov 1 09:59:27.819375 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 1 09:59:27.821654 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 1 09:59:27.825088 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 1 09:59:27.845057 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 1 09:59:27.848157 kernel: BTRFS info (device vda6): last unmount of filesystem 75c18d9e-3deb-43e1-a433-af20f45ab517 Nov 1 09:59:27.861303 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 1 09:59:27.888185 ignition[1034]: INFO : Ignition 2.22.0 Nov 1 09:59:27.888185 ignition[1034]: INFO : Stage: mount Nov 1 09:59:27.891281 ignition[1034]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 1 09:59:27.891281 ignition[1034]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 1 09:59:27.891281 ignition[1034]: INFO : mount: mount passed Nov 1 09:59:27.891281 ignition[1034]: INFO : Ignition finished successfully Nov 1 09:59:27.901017 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 1 09:59:27.903494 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 1 09:59:27.936667 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 1 09:59:27.963261 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1046) Nov 1 09:59:27.967221 kernel: BTRFS info (device vda6): first mount of filesystem 75c18d9e-3deb-43e1-a433-af20f45ab517 Nov 1 09:59:27.967286 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 1 09:59:27.971208 kernel: BTRFS info (device vda6): turning on async discard Nov 1 09:59:27.971254 kernel: BTRFS info (device vda6): enabling free space tree Nov 1 09:59:27.973676 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 1 09:59:28.072992 ignition[1063]: INFO : Ignition 2.22.0 Nov 1 09:59:28.072992 ignition[1063]: INFO : Stage: files Nov 1 09:59:28.075752 ignition[1063]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 1 09:59:28.075752 ignition[1063]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 1 09:59:28.079533 ignition[1063]: DEBUG : files: compiled without relabeling support, skipping Nov 1 09:59:28.082594 ignition[1063]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 1 09:59:28.082594 ignition[1063]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 1 09:59:28.092360 ignition[1063]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 1 09:59:28.094986 ignition[1063]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 1 09:59:28.097907 unknown[1063]: wrote ssh authorized keys file for user: core Nov 1 09:59:28.099914 ignition[1063]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 1 09:59:28.108617 ignition[1063]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 1 09:59:28.112890 ignition[1063]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 1 09:59:28.112890 ignition[1063]: INFO : files: op(4): [started] processing unit "etcd-member.service" Nov 1 09:59:28.112890 ignition[1063]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Nov 1 09:59:28.123014 ignition[1063]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Nov 1 09:59:28.153672 ignition[1063]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 1 09:59:28.158517 ignition[1063]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 1 09:59:28.161336 ignition[1063]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Nov 1 09:59:28.161336 ignition[1063]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Nov 1 09:59:28.161336 ignition[1063]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Nov 1 09:59:28.161336 ignition[1063]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 1 09:59:28.161336 ignition[1063]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 1 09:59:28.161336 ignition[1063]: INFO : files: files passed Nov 1 09:59:28.161336 ignition[1063]: INFO : Ignition finished successfully Nov 1 09:59:28.172615 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 1 09:59:28.181552 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 1 09:59:28.185602 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 1 09:59:28.199867 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 1 09:59:28.200010 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 1 09:59:28.207338 initrd-setup-root-after-ignition[1095]: grep: /sysroot/oem/oem-release: No such file or directory Nov 1 09:59:28.209869 initrd-setup-root-after-ignition[1097]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 1 09:59:28.209869 initrd-setup-root-after-ignition[1097]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 1 09:59:28.215511 initrd-setup-root-after-ignition[1101]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 1 09:59:28.219931 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 1 09:59:28.222594 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 1 09:59:28.224896 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 1 09:59:28.302916 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 1 09:59:28.303062 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 1 09:59:28.304998 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 1 09:59:28.312065 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 1 09:59:28.317449 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 1 09:59:28.318795 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 1 09:59:28.359079 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 1 09:59:28.363195 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 1 09:59:28.397508 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 1 09:59:28.397843 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 1 09:59:28.406355 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 1 09:59:28.413589 systemd[1]: Stopped target timers.target - Timer Units. Nov 1 09:59:28.414802 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 1 09:59:28.414999 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 1 09:59:28.425138 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 1 09:59:28.428772 systemd[1]: Stopped target basic.target - Basic System. Nov 1 09:59:28.429851 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 1 09:59:28.432840 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 1 09:59:28.440355 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 1 09:59:28.441173 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 1 09:59:28.442024 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 1 09:59:28.448175 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 1 09:59:28.451755 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 1 09:59:28.458090 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 1 09:59:28.458874 systemd[1]: Stopped target swap.target - Swaps. Nov 1 09:59:28.462099 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 1 09:59:28.462335 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 1 09:59:28.467146 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 1 09:59:28.468127 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 1 09:59:28.472643 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 1 09:59:28.472843 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 1 09:59:28.504087 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 1 09:59:28.504356 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 1 09:59:28.505505 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 1 09:59:28.505662 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 1 09:59:28.506136 systemd[1]: Stopped target paths.target - Path Units. Nov 1 09:59:28.513304 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 1 09:59:28.520315 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 1 09:59:28.521132 systemd[1]: Stopped target slices.target - Slice Units. Nov 1 09:59:28.521692 systemd[1]: Stopped target sockets.target - Socket Units. Nov 1 09:59:28.522289 systemd[1]: iscsid.socket: Deactivated successfully. Nov 1 09:59:28.522410 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 1 09:59:28.522880 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 1 09:59:28.523030 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 1 09:59:28.524476 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 1 09:59:28.524687 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 1 09:59:28.537108 systemd[1]: ignition-files.service: Deactivated successfully. Nov 1 09:59:28.537305 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 1 09:59:28.544143 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 1 09:59:28.546143 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 1 09:59:28.559712 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 1 09:59:28.560073 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 1 09:59:28.571930 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 1 09:59:28.572127 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 1 09:59:28.578388 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 1 09:59:28.578621 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 1 09:59:28.591587 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 1 09:59:28.591755 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 1 09:59:28.604919 ignition[1121]: INFO : Ignition 2.22.0 Nov 1 09:59:28.604919 ignition[1121]: INFO : Stage: umount Nov 1 09:59:28.609386 ignition[1121]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 1 09:59:28.609386 ignition[1121]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 1 09:59:28.609386 ignition[1121]: INFO : umount: umount passed Nov 1 09:59:28.609386 ignition[1121]: INFO : Ignition finished successfully Nov 1 09:59:28.605610 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 1 09:59:28.613063 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 1 09:59:28.613260 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 1 09:59:28.615550 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 1 09:59:28.615706 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 1 09:59:28.618641 systemd[1]: Stopped target network.target - Network. Nov 1 09:59:28.620633 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 1 09:59:28.620719 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 1 09:59:28.623710 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 1 09:59:28.623769 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 1 09:59:28.626879 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 1 09:59:28.626970 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 1 09:59:28.629215 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 1 09:59:28.629352 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 1 09:59:28.629997 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 1 09:59:28.630066 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 1 09:59:28.630948 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 1 09:59:28.638319 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 1 09:59:28.661980 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 1 09:59:28.662199 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 1 09:59:28.667785 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 1 09:59:28.667991 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 1 09:59:28.674516 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 1 09:59:28.675422 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 1 09:59:28.675478 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 1 09:59:28.683207 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 1 09:59:28.683884 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 1 09:59:28.684000 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 1 09:59:28.684881 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 1 09:59:28.684946 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 1 09:59:28.685725 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 1 09:59:28.685788 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 1 09:59:28.701938 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 1 09:59:28.727179 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 1 09:59:28.727399 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 1 09:59:28.732357 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 1 09:59:28.732425 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 1 09:59:28.734913 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 1 09:59:28.734963 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 1 09:59:28.735858 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 1 09:59:28.735928 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 1 09:59:28.744808 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 1 09:59:28.744912 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 1 09:59:28.746624 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 1 09:59:28.746682 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 1 09:59:28.781381 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 1 09:59:28.782206 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 1 09:59:28.782333 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 1 09:59:28.789264 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 1 09:59:28.789344 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 1 09:59:28.790170 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 1 09:59:28.790262 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 1 09:59:28.790787 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 1 09:59:28.790856 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 1 09:59:28.791695 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 1 09:59:28.791763 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 09:59:28.820018 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 1 09:59:28.820217 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 1 09:59:28.841307 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 1 09:59:28.841470 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 1 09:59:28.845752 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 1 09:59:28.849206 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 1 09:59:28.874877 systemd[1]: Switching root. Nov 1 09:59:28.908334 systemd-journald[316]: Journal stopped Nov 1 09:59:30.154491 systemd-journald[316]: Received SIGTERM from PID 1 (systemd). Nov 1 09:59:30.154596 kernel: SELinux: policy capability network_peer_controls=1 Nov 1 09:59:30.154617 kernel: SELinux: policy capability open_perms=1 Nov 1 09:59:30.154633 kernel: SELinux: policy capability extended_socket_class=1 Nov 1 09:59:30.154649 kernel: SELinux: policy capability always_check_network=0 Nov 1 09:59:30.154664 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 1 09:59:30.154684 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 1 09:59:30.154700 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 1 09:59:30.154731 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 1 09:59:30.154748 kernel: SELinux: policy capability userspace_initial_context=0 Nov 1 09:59:30.154764 kernel: audit: type=1403 audit(1761991169.073:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 1 09:59:30.154781 systemd[1]: Successfully loaded SELinux policy in 68.887ms. Nov 1 09:59:30.154807 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.243ms. Nov 1 09:59:30.154833 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) Nov 1 09:59:30.154858 systemd[1]: Detected virtualization kvm. Nov 1 09:59:30.154876 systemd[1]: Detected architecture x86-64. Nov 1 09:59:30.154892 systemd[1]: Detected first boot. Nov 1 09:59:30.154909 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 1 09:59:30.154926 zram_generator::config[1171]: No configuration found. Nov 1 09:59:30.154944 kernel: Guest personality initialized and is inactive Nov 1 09:59:30.154968 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Nov 1 09:59:30.154986 kernel: Initialized host personality Nov 1 09:59:30.154999 kernel: NET: Registered PF_VSOCK protocol family Nov 1 09:59:30.155011 systemd[1]: Populated /etc with preset unit settings. Nov 1 09:59:30.155024 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 1 09:59:30.155037 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 1 09:59:30.155050 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 1 09:59:30.155070 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 1 09:59:30.155083 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 1 09:59:30.155110 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 1 09:59:30.155129 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 1 09:59:30.155144 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 1 09:59:30.155157 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 1 09:59:30.155170 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 1 09:59:30.155184 systemd[1]: Created slice user.slice - User and Session Slice. Nov 1 09:59:30.155198 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 1 09:59:30.155211 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 1 09:59:30.155224 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 1 09:59:30.155273 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 1 09:59:30.155291 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 1 09:59:30.155323 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 1 09:59:30.155341 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 1 09:59:30.155357 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 1 09:59:30.155370 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 1 09:59:30.155386 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 1 09:59:30.155400 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 1 09:59:30.155412 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 1 09:59:30.155426 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 1 09:59:30.155438 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 1 09:59:30.155451 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 1 09:59:30.155463 systemd[1]: Reached target slices.target - Slice Units. Nov 1 09:59:30.155476 systemd[1]: Reached target swap.target - Swaps. Nov 1 09:59:30.155499 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 1 09:59:30.155512 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 1 09:59:30.155525 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 1 09:59:30.155538 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 1 09:59:30.155550 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 1 09:59:30.155563 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 1 09:59:30.155575 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 1 09:59:30.155590 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 1 09:59:30.155603 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 1 09:59:30.155617 systemd[1]: Mounting media.mount - External Media Directory... Nov 1 09:59:30.155630 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:30.155643 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 1 09:59:30.155655 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 1 09:59:30.155668 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 1 09:59:30.155687 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 1 09:59:30.155700 systemd[1]: Reached target machines.target - Containers. Nov 1 09:59:30.155713 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 1 09:59:30.155726 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 1 09:59:30.155739 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 1 09:59:30.155752 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 1 09:59:30.155769 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 1 09:59:30.155783 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 1 09:59:30.155796 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 1 09:59:30.155809 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 1 09:59:30.155821 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 1 09:59:30.155834 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 1 09:59:30.155847 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 1 09:59:30.155865 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 1 09:59:30.155877 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 1 09:59:30.155890 systemd[1]: Stopped systemd-fsck-usr.service. Nov 1 09:59:30.156124 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). Nov 1 09:59:30.156137 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 1 09:59:30.156150 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 1 09:59:30.156163 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 1 09:59:30.156182 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 1 09:59:30.156195 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 1 09:59:30.156209 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 1 09:59:30.156223 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:30.156270 kernel: ACPI: bus type drm_connector registered Nov 1 09:59:30.156284 kernel: fuse: init (API version 7.41) Nov 1 09:59:30.156297 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 1 09:59:30.156310 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 1 09:59:30.156322 systemd[1]: Mounted media.mount - External Media Directory. Nov 1 09:59:30.156335 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 1 09:59:30.156348 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 1 09:59:30.156367 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 1 09:59:30.156403 systemd-journald[1238]: Collecting audit messages is disabled. Nov 1 09:59:30.156427 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 1 09:59:30.156439 systemd-journald[1238]: Journal started Nov 1 09:59:30.156460 systemd-journald[1238]: Runtime Journal (/run/log/journal/1406e6af5093484a8b02a3517c282912) is 6M, max 48.1M, 42M free. Nov 1 09:59:29.768637 systemd[1]: Queued start job for default target multi-user.target. Nov 1 09:59:29.794455 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 1 09:59:29.795019 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 1 09:59:30.159557 systemd[1]: Started systemd-journald.service - Journal Service. Nov 1 09:59:30.162573 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 1 09:59:30.164827 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 1 09:59:30.165129 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 1 09:59:30.167524 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 1 09:59:30.167790 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 1 09:59:30.170097 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 1 09:59:30.170418 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 1 09:59:30.172975 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 1 09:59:30.173327 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 1 09:59:30.176099 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 1 09:59:30.176367 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 1 09:59:30.178812 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 1 09:59:30.179091 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 1 09:59:30.181384 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 1 09:59:30.183885 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 1 09:59:30.187250 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 1 09:59:30.189851 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 1 09:59:30.211502 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 1 09:59:30.214528 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 1 09:59:30.218437 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 1 09:59:30.222204 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 1 09:59:30.224303 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 1 09:59:30.224455 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 1 09:59:30.227723 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 1 09:59:30.230501 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 1 09:59:30.233506 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 1 09:59:30.238766 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 1 09:59:30.241369 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 1 09:59:30.242720 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 1 09:59:30.244731 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 1 09:59:30.250579 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 1 09:59:30.256566 systemd-journald[1238]: Time spent on flushing to /var/log/journal/1406e6af5093484a8b02a3517c282912 is 17.439ms for 1025 entries. Nov 1 09:59:30.256566 systemd-journald[1238]: System Journal (/var/log/journal/1406e6af5093484a8b02a3517c282912) is 8M, max 163.5M, 155.5M free. Nov 1 09:59:30.282345 systemd-journald[1238]: Received client request to flush runtime journal. Nov 1 09:59:30.256551 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 1 09:59:30.268489 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 1 09:59:30.272179 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 1 09:59:30.275888 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 1 09:59:30.278139 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 1 09:59:30.280685 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 1 09:59:30.284331 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 1 09:59:30.290940 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 1 09:59:30.293632 kernel: loop1: detected capacity change from 0 to 119080 Nov 1 09:59:30.300215 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 1 09:59:30.326945 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 1 09:59:30.352278 kernel: loop2: detected capacity change from 0 to 111544 Nov 1 09:59:30.353848 systemd-tmpfiles[1287]: ACLs are not supported, ignoring. Nov 1 09:59:30.353923 systemd-tmpfiles[1287]: ACLs are not supported, ignoring. Nov 1 09:59:30.359827 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 1 09:59:30.363803 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 1 09:59:30.370488 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 1 09:59:30.398268 kernel: loop3: detected capacity change from 0 to 119080 Nov 1 09:59:30.410270 kernel: loop4: detected capacity change from 0 to 111544 Nov 1 09:59:30.418489 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 1 09:59:30.421424 (sd-merge)[1306]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw'. Nov 1 09:59:30.423551 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 1 09:59:30.426453 (sd-merge)[1306]: Merged extensions into '/usr'. Nov 1 09:59:30.429453 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 1 09:59:30.431888 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 1 09:59:30.437075 systemd[1]: Starting ensure-sysext.service... Nov 1 09:59:30.441530 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 1 09:59:30.448600 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 1 09:59:30.458752 systemd[1]: Reload requested from client PID 1311 ('systemctl') (unit ensure-sysext.service)... Nov 1 09:59:30.458772 systemd[1]: Reloading... Nov 1 09:59:30.475084 systemd-tmpfiles[1309]: ACLs are not supported, ignoring. Nov 1 09:59:30.475123 systemd-tmpfiles[1309]: ACLs are not supported, ignoring. Nov 1 09:59:30.492042 systemd-tmpfiles[1312]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 1 09:59:30.492652 systemd-tmpfiles[1312]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 1 09:59:30.493020 systemd-tmpfiles[1312]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 1 09:59:30.493421 systemd-tmpfiles[1312]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 1 09:59:30.494512 systemd-tmpfiles[1312]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 1 09:59:30.494916 systemd-tmpfiles[1312]: ACLs are not supported, ignoring. Nov 1 09:59:30.495079 systemd-tmpfiles[1312]: ACLs are not supported, ignoring. Nov 1 09:59:30.503148 systemd-tmpfiles[1312]: Detected autofs mount point /boot during canonicalization of boot. Nov 1 09:59:30.503289 systemd-tmpfiles[1312]: Skipping /boot Nov 1 09:59:30.520525 systemd-tmpfiles[1312]: Detected autofs mount point /boot during canonicalization of boot. Nov 1 09:59:30.520674 systemd-tmpfiles[1312]: Skipping /boot Nov 1 09:59:30.575275 zram_generator::config[1352]: No configuration found. Nov 1 09:59:30.654437 systemd-resolved[1308]: Positive Trust Anchors: Nov 1 09:59:30.654466 systemd-resolved[1308]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 1 09:59:30.654479 systemd-resolved[1308]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 1 09:59:30.654521 systemd-resolved[1308]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 1 09:59:30.659617 systemd-resolved[1308]: Defaulting to hostname 'linux'. Nov 1 09:59:30.798205 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 1 09:59:30.798886 systemd[1]: Reloading finished in 339 ms. Nov 1 09:59:30.828388 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 1 09:59:30.831003 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 1 09:59:30.836457 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 1 09:59:30.875639 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 1 09:59:30.883287 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 1 09:59:30.889423 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 1 09:59:30.933934 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 1 09:59:30.939385 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 1 09:59:30.946535 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 1 09:59:30.949989 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 1 09:59:30.957049 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:30.957343 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 1 09:59:30.958939 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 1 09:59:30.963987 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 1 09:59:30.971521 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 1 09:59:30.973814 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 1 09:59:30.973947 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). Nov 1 09:59:30.974059 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:30.977817 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:30.978077 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 1 09:59:30.978364 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 1 09:59:30.978461 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). Nov 1 09:59:30.978561 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:30.982513 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 1 09:59:30.982827 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 1 09:59:30.985429 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 1 09:59:30.985763 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 1 09:59:30.988495 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 1 09:59:30.991464 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 1 09:59:30.991798 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 1 09:59:31.004443 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 1 09:59:31.013785 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:31.014209 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 1 09:59:31.017308 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 1 09:59:31.024130 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 1 09:59:31.030294 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 1 09:59:31.035640 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 1 09:59:31.038116 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 1 09:59:31.038416 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). Nov 1 09:59:31.038657 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 09:59:31.050858 systemd[1]: Finished ensure-sysext.service. Nov 1 09:59:31.053212 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 1 09:59:31.053494 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 1 09:59:31.056793 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 1 09:59:31.057273 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 1 09:59:31.061261 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 1 09:59:31.061710 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 1 09:59:31.064536 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 1 09:59:31.065032 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 1 09:59:31.076874 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 1 09:59:31.076965 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 1 09:59:31.079512 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 1 09:59:31.129459 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 1 09:59:31.132474 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 1 09:59:31.270432 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 1 09:59:31.272931 systemd[1]: Reached target time-set.target - System Time Set. Nov 1 09:59:31.279509 augenrules[1436]: No rules Nov 1 09:59:31.281257 systemd[1]: audit-rules.service: Deactivated successfully. Nov 1 09:59:31.281566 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 1 09:59:31.426278 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 1 09:59:31.430734 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 1 09:59:31.482050 systemd-udevd[1443]: Using default interface naming scheme 'v257'. Nov 1 09:59:31.509950 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 1 09:59:31.533017 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 1 09:59:31.634715 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 1 09:59:31.671278 kernel: mousedev: PS/2 mouse device common for all mice Nov 1 09:59:31.686168 systemd-networkd[1460]: lo: Link UP Nov 1 09:59:31.686190 systemd-networkd[1460]: lo: Gained carrier Nov 1 09:59:31.690220 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 1 09:59:31.693713 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 1 09:59:31.695856 systemd[1]: Reached target network.target - Network. Nov 1 09:59:31.698714 systemd-networkd[1460]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 1 09:59:31.698794 systemd-networkd[1460]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 1 09:59:31.700862 systemd-networkd[1460]: eth0: Link UP Nov 1 09:59:31.700878 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 1 09:59:31.703430 systemd-networkd[1460]: eth0: Gained carrier Nov 1 09:59:31.703469 systemd-networkd[1460]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 1 09:59:31.705886 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 1 09:59:31.706264 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Nov 1 09:59:31.713097 kernel: ACPI: button: Power Button [PWRF] Nov 1 09:59:31.714912 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 1 09:59:31.719276 systemd-networkd[1460]: eth0: DHCPv4 address 10.0.0.18/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 1 09:59:31.720317 systemd-timesyncd[1428]: Network configuration changed, trying to establish connection. Nov 1 09:59:31.723367 systemd-timesyncd[1428]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 1 09:59:31.723508 systemd-timesyncd[1428]: Initial clock synchronization to Sat 2025-11-01 09:59:32.069197 UTC. Nov 1 09:59:31.733560 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 1 09:59:31.750028 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 1 09:59:31.761256 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Nov 1 09:59:31.765500 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Nov 1 09:59:31.765877 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Nov 1 09:59:31.836663 ldconfig[1392]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 1 09:59:31.907147 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 09:59:31.949102 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 1 09:59:31.951359 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 09:59:31.958961 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 09:59:31.976821 kernel: kvm_amd: TSC scaling supported Nov 1 09:59:31.976877 kernel: kvm_amd: Nested Virtualization enabled Nov 1 09:59:31.976912 kernel: kvm_amd: Nested Paging enabled Nov 1 09:59:31.977758 kernel: kvm_amd: LBR virtualization supported Nov 1 09:59:31.979345 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Nov 1 09:59:31.979366 kernel: kvm_amd: Virtual GIF supported Nov 1 09:59:31.984998 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 1 09:59:31.994565 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 1 09:59:32.016219 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 1 09:59:32.029316 kernel: EDAC MC: Ver: 3.0.0 Nov 1 09:59:32.066064 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 09:59:32.070361 systemd[1]: Reached target sysinit.target - System Initialization. Nov 1 09:59:32.072561 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 1 09:59:32.074737 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 1 09:59:32.077177 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 1 09:59:32.079808 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 1 09:59:32.082055 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 1 09:59:32.084590 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 1 09:59:32.087101 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 1 09:59:32.087141 systemd[1]: Reached target paths.target - Path Units. Nov 1 09:59:32.089671 systemd[1]: Reached target timers.target - Timer Units. Nov 1 09:59:32.093033 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 1 09:59:32.097246 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 1 09:59:32.101446 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 1 09:59:32.103727 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 1 09:59:32.105798 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 1 09:59:32.110692 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 1 09:59:32.113027 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 1 09:59:32.115939 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 1 09:59:32.118686 systemd[1]: Reached target sockets.target - Socket Units. Nov 1 09:59:32.120407 systemd[1]: Reached target basic.target - Basic System. Nov 1 09:59:32.122113 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 1 09:59:32.122181 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 1 09:59:32.123610 systemd[1]: Starting containerd.service - containerd container runtime... Nov 1 09:59:32.126732 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 1 09:59:32.129579 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 1 09:59:32.132965 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 1 09:59:32.136050 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 1 09:59:32.136900 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 1 09:59:32.139377 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 1 09:59:32.144500 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 1 09:59:32.144697 jq[1514]: false Nov 1 09:59:32.149372 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 1 09:59:32.157398 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 1 09:59:32.158901 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Refreshing passwd entry cache Nov 1 09:59:32.158912 oslogin_cache_refresh[1516]: Refreshing passwd entry cache Nov 1 09:59:32.168216 extend-filesystems[1515]: Found /dev/vda6 Nov 1 09:59:32.170843 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Failure getting users, quitting Nov 1 09:59:32.170835 oslogin_cache_refresh[1516]: Failure getting users, quitting Nov 1 09:59:32.170936 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 1 09:59:32.170865 oslogin_cache_refresh[1516]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 1 09:59:32.170995 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Refreshing group entry cache Nov 1 09:59:32.170944 oslogin_cache_refresh[1516]: Refreshing group entry cache Nov 1 09:59:32.171868 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 1 09:59:32.173107 extend-filesystems[1515]: Found /dev/vda9 Nov 1 09:59:32.174345 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 1 09:59:32.175147 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 1 09:59:32.177945 systemd[1]: Starting update-engine.service - Update Engine... Nov 1 09:59:32.179431 extend-filesystems[1515]: Checking size of /dev/vda9 Nov 1 09:59:32.188076 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Failure getting groups, quitting Nov 1 09:59:32.188076 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 1 09:59:32.184939 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 1 09:59:32.179846 oslogin_cache_refresh[1516]: Failure getting groups, quitting Nov 1 09:59:32.179867 oslogin_cache_refresh[1516]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 1 09:59:32.192989 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 1 09:59:32.196557 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 1 09:59:32.196912 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 1 09:59:32.197324 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 1 09:59:32.197619 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 1 09:59:32.205247 systemd[1]: motdgen.service: Deactivated successfully. Nov 1 09:59:32.205716 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 1 09:59:32.208306 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 1 09:59:32.208638 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 1 09:59:32.353481 extend-filesystems[1515]: Resized partition /dev/vda9 Nov 1 09:59:32.360228 extend-filesystems[1550]: resize2fs 1.47.3 (8-Jul-2025) Nov 1 09:59:32.363099 jq[1535]: true Nov 1 09:59:32.367086 update_engine[1531]: I20251101 09:59:32.366988 1531 main.cc:92] Flatcar Update Engine starting Nov 1 09:59:32.373301 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 474107 blocks Nov 1 09:59:32.373348 kernel: EXT4-fs (vda9): resized filesystem to 474107 Nov 1 09:59:32.379152 jq[1554]: true Nov 1 09:59:32.393015 extend-filesystems[1550]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 1 09:59:32.393015 extend-filesystems[1550]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 1 09:59:32.393015 extend-filesystems[1550]: The filesystem on /dev/vda9 is now 474107 (4k) blocks long. Nov 1 09:59:32.407732 extend-filesystems[1515]: Resized filesystem in /dev/vda9 Nov 1 09:59:32.395827 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 1 09:59:32.398965 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 1 09:59:32.424006 dbus-daemon[1512]: [system] SELinux support is enabled Nov 1 09:59:32.424261 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 1 09:59:32.429667 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 1 09:59:32.429703 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 1 09:59:32.433082 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 1 09:59:32.433108 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 1 09:59:32.449732 systemd[1]: Started update-engine.service - Update Engine. Nov 1 09:59:32.451778 update_engine[1531]: I20251101 09:59:32.451109 1531 update_check_scheduler.cc:74] Next update check in 6m17s Nov 1 09:59:32.459566 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 1 09:59:32.479690 bash[1577]: Updated "/home/core/.ssh/authorized_keys" Nov 1 09:59:32.487574 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 1 09:59:32.489508 systemd-logind[1525]: Watching system buttons on /dev/input/event2 (Power Button) Nov 1 09:59:32.489539 systemd-logind[1525]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 1 09:59:32.489943 systemd-logind[1525]: New seat seat0. Nov 1 09:59:32.492421 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 1 09:59:32.492553 systemd[1]: Started systemd-logind.service - User Login Management. Nov 1 09:59:32.580726 locksmithd[1578]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 1 09:59:32.742873 containerd[1549]: time="2025-11-01T09:59:32Z" 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 Nov 1 09:59:32.743694 containerd[1549]: time="2025-11-01T09:59:32.743630422Z" level=info msg="starting containerd" revision=75cb2b7193e4e490e9fbdc236c0e811ccaba3376 version=v2.1.4 Nov 1 09:59:32.758039 containerd[1549]: time="2025-11-01T09:59:32.757934847Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="21.301µs" Nov 1 09:59:32.758039 containerd[1549]: time="2025-11-01T09:59:32.758005545Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 1 09:59:32.758219 containerd[1549]: time="2025-11-01T09:59:32.758087238Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 1 09:59:32.758219 containerd[1549]: time="2025-11-01T09:59:32.758120392Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 1 09:59:32.758517 containerd[1549]: time="2025-11-01T09:59:32.758474944Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 1 09:59:32.758517 containerd[1549]: time="2025-11-01T09:59:32.758503750Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 1 09:59:32.758657 containerd[1549]: time="2025-11-01T09:59:32.758619213Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 1 09:59:32.758657 containerd[1549]: time="2025-11-01T09:59:32.758642187Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 1 09:59:32.759156 containerd[1549]: time="2025-11-01T09:59:32.759113824Z" 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 Nov 1 09:59:32.759156 containerd[1549]: time="2025-11-01T09:59:32.759138124Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 1 09:59:32.759156 containerd[1549]: time="2025-11-01T09:59:32.759153939Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 1 09:59:32.759271 containerd[1549]: time="2025-11-01T09:59:32.759166240Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 1 09:59:32.759455 containerd[1549]: time="2025-11-01T09:59:32.759422471Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 1 09:59:32.759455 containerd[1549]: time="2025-11-01T09:59:32.759444368Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 1 09:59:32.759624 containerd[1549]: time="2025-11-01T09:59:32.759596047Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 1 09:59:32.760013 containerd[1549]: time="2025-11-01T09:59:32.759980859Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 1 09:59:32.760053 containerd[1549]: time="2025-11-01T09:59:32.760032983Z" 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 Nov 1 09:59:32.760089 containerd[1549]: time="2025-11-01T09:59:32.760052768Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 1 09:59:32.760161 containerd[1549]: time="2025-11-01T09:59:32.760128336Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 1 09:59:32.760525 containerd[1549]: time="2025-11-01T09:59:32.760490028Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 1 09:59:32.760648 containerd[1549]: time="2025-11-01T09:59:32.760620197Z" level=info msg="metadata content store policy set" policy=shared Nov 1 09:59:32.766408 containerd[1549]: time="2025-11-01T09:59:32.766349088Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 1 09:59:32.766408 containerd[1549]: time="2025-11-01T09:59:32.766412427Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 1 09:59:32.766559 containerd[1549]: time="2025-11-01T09:59:32.766530430Z" level=info msg="skip loading plugin" error="could not find mkfs.erofs: exec: \"mkfs.erofs\": executable file not found in $PATH: skip plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 1 09:59:32.766559 containerd[1549]: time="2025-11-01T09:59:32.766545983Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 1 09:59:32.766635 containerd[1549]: time="2025-11-01T09:59:32.766586997Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 1 09:59:32.766635 containerd[1549]: time="2025-11-01T09:59:32.766600876Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 1 09:59:32.766635 containerd[1549]: time="2025-11-01T09:59:32.766612677Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 1 09:59:32.766635 containerd[1549]: time="2025-11-01T09:59:32.766622314Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 1 09:59:32.766635 containerd[1549]: time="2025-11-01T09:59:32.766634282Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 1 09:59:32.766754 containerd[1549]: time="2025-11-01T09:59:32.766646667Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 1 09:59:32.766754 containerd[1549]: time="2025-11-01T09:59:32.766658498Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 1 09:59:32.766754 containerd[1549]: time="2025-11-01T09:59:32.766670539Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 1 09:59:32.766754 containerd[1549]: time="2025-11-01T09:59:32.766680918Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 1 09:59:32.766754 containerd[1549]: time="2025-11-01T09:59:32.766693690Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 1 09:59:32.766884 containerd[1549]: time="2025-11-01T09:59:32.766849456Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 1 09:59:32.766910 containerd[1549]: time="2025-11-01T09:59:32.766897463Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 1 09:59:32.766941 containerd[1549]: time="2025-11-01T09:59:32.766913308Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 1 09:59:32.766941 containerd[1549]: time="2025-11-01T09:59:32.766931118Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 1 09:59:32.767001 containerd[1549]: time="2025-11-01T09:59:32.766942751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 1 09:59:32.767001 containerd[1549]: time="2025-11-01T09:59:32.766952608Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 1 09:59:32.767001 containerd[1549]: time="2025-11-01T09:59:32.766965474Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 1 09:59:32.767001 containerd[1549]: time="2025-11-01T09:59:32.766979898Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 1 09:59:32.767001 containerd[1549]: time="2025-11-01T09:59:32.766993464Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 1 09:59:32.767121 containerd[1549]: time="2025-11-01T09:59:32.767007919Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 1 09:59:32.767121 containerd[1549]: time="2025-11-01T09:59:32.767019877Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 1 09:59:32.767121 containerd[1549]: time="2025-11-01T09:59:32.767050480Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 1 09:59:32.767121 containerd[1549]: time="2025-11-01T09:59:32.767113286Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 1 09:59:32.767213 containerd[1549]: time="2025-11-01T09:59:32.767132821Z" level=info msg="Start snapshots syncer" Nov 1 09:59:32.767213 containerd[1549]: time="2025-11-01T09:59:32.767179948Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 1 09:59:32.767679 containerd[1549]: time="2025-11-01T09:59:32.767627493Z" 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,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":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\"}" Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767691000Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767770592Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767903813Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767925950Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767935911Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767970079Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767983635Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.767996125Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.768009504Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.768020614Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.768030857Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.768073219Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.768085354Z" 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 Nov 1 09:59:32.768111 containerd[1549]: time="2025-11-01T09:59:32.768093788Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768102914Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768117912Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768127424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768138628Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768151840Z" level=info msg="runtime interface created" Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768158497Z" level=info msg="created NRI interface" Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768169450Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768182839Z" level=info msg="Connect containerd service" Nov 1 09:59:32.768470 containerd[1549]: time="2025-11-01T09:59:32.768205333Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 1 09:59:32.769321 containerd[1549]: time="2025-11-01T09:59:32.769257912Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 1 09:59:32.964074 containerd[1549]: time="2025-11-01T09:59:32.963957405Z" level=info msg="Start subscribing containerd event" Nov 1 09:59:32.964318 containerd[1549]: time="2025-11-01T09:59:32.964117530Z" level=info msg="Start recovering state" Nov 1 09:59:32.964451 containerd[1549]: time="2025-11-01T09:59:32.964428319Z" level=info msg="Start event monitor" Nov 1 09:59:32.964484 containerd[1549]: time="2025-11-01T09:59:32.964468058Z" level=info msg="Start cni network conf syncer for default" Nov 1 09:59:32.964484 containerd[1549]: time="2025-11-01T09:59:32.964479785Z" level=info msg="Start streaming server" Nov 1 09:59:32.964535 containerd[1549]: time="2025-11-01T09:59:32.964491293Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 1 09:59:32.964934 containerd[1549]: time="2025-11-01T09:59:32.964507723Z" level=info msg="runtime interface starting up..." Nov 1 09:59:32.964934 containerd[1549]: time="2025-11-01T09:59:32.964918843Z" level=info msg="starting plugins..." Nov 1 09:59:32.964934 containerd[1549]: time="2025-11-01T09:59:32.964942192Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 1 09:59:32.965142 containerd[1549]: time="2025-11-01T09:59:32.964803944Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 1 09:59:32.965142 containerd[1549]: time="2025-11-01T09:59:32.965082584Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 1 09:59:32.967953 systemd[1]: Started containerd.service - containerd container runtime. Nov 1 09:59:32.970590 containerd[1549]: time="2025-11-01T09:59:32.970531632Z" level=info msg="containerd successfully booted in 0.229226s" Nov 1 09:59:33.002864 sshd_keygen[1536]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 1 09:59:33.048639 systemd-networkd[1460]: eth0: Gained IPv6LL Nov 1 09:59:33.054129 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 1 09:59:33.057375 systemd[1]: Reached target network-online.target - Network is Online. Nov 1 09:59:33.061982 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 1 09:59:33.065456 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 1 09:59:33.072540 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 1 09:59:33.079715 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 1 09:59:33.092396 systemd[1]: issuegen.service: Deactivated successfully. Nov 1 09:59:33.098551 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 1 09:59:33.101387 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 1 09:59:33.103706 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 1 09:59:33.103995 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 1 09:59:33.109529 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Nov 1 09:59:33.111545 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 1 09:59:33.113377 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 1 09:59:33.124258 (-wrapper)[1633]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Nov 1 09:59:33.134123 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 1 09:59:33.138497 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 1 09:59:33.141865 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 1 09:59:33.144143 systemd[1]: Reached target getty.target - Login Prompts. Nov 1 09:59:33.357945 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 1 09:59:33.385879 (dockerd)[1649]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Nov 1 09:59:34.247527 dockerd[1649]: time="2025-11-01T09:59:34.247408702Z" level=info msg="Starting up" Nov 1 09:59:34.248256 dockerd[1649]: time="2025-11-01T09:59:34.248221772Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 1 09:59:34.272255 dockerd[1649]: time="2025-11-01T09:59:34.272182396Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 1 09:59:35.423371 dockerd[1649]: time="2025-11-01T09:59:35.423310429Z" level=info msg="Loading containers: start." Nov 1 09:59:35.599282 kernel: Initializing XFRM netlink socket Nov 1 09:59:36.060747 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 1 09:59:36.065058 systemd[1]: Started sshd@0-10.0.0.18:22-10.0.0.1:52654.service - OpenSSH per-connection server daemon (10.0.0.1:52654). Nov 1 09:59:36.155865 sshd[1736]: Accepted publickey for core from 10.0.0.1 port 52654 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:36.158243 sshd-session[1736]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:36.168528 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 1 09:59:36.172207 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 1 09:59:36.184350 systemd-logind[1525]: New session 1 of user core. Nov 1 09:59:36.209911 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 1 09:59:36.217400 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 1 09:59:36.233167 (systemd)[1811]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 1 09:59:36.236384 systemd-logind[1525]: New session c1 of user core. Nov 1 09:59:36.258080 systemd-networkd[1460]: docker0: Link UP Nov 1 09:59:36.264501 dockerd[1649]: time="2025-11-01T09:59:36.264437415Z" level=info msg="Loading containers: done." Nov 1 09:59:36.315952 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1295897639-merged.mount: Deactivated successfully. Nov 1 09:59:36.392670 systemd[1811]: Queued start job for default target default.target. Nov 1 09:59:36.410218 systemd[1811]: Created slice app.slice - User Application Slice. Nov 1 09:59:36.410268 systemd[1811]: Reached target paths.target - Paths. Nov 1 09:59:36.410334 systemd[1811]: Reached target timers.target - Timers. Nov 1 09:59:36.412425 systemd[1811]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 1 09:59:36.426999 systemd[1811]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 1 09:59:36.427171 systemd[1811]: Reached target sockets.target - Sockets. Nov 1 09:59:36.427244 systemd[1811]: Reached target basic.target - Basic System. Nov 1 09:59:36.427336 systemd[1811]: Reached target default.target - Main User Target. Nov 1 09:59:36.427384 systemd[1811]: Startup finished in 181ms. Nov 1 09:59:36.427850 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 1 09:59:36.428069 dockerd[1649]: time="2025-11-01T09:59:36.427928274Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Nov 1 09:59:36.428450 dockerd[1649]: time="2025-11-01T09:59:36.428165512Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 1 09:59:36.428450 dockerd[1649]: time="2025-11-01T09:59:36.428354489Z" level=info msg="Initializing buildkit" Nov 1 09:59:36.432578 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 1 09:59:36.460106 systemd[1]: Started sshd@1-10.0.0.18:22-10.0.0.1:52662.service - OpenSSH per-connection server daemon (10.0.0.1:52662). Nov 1 09:59:36.520752 sshd[1848]: Accepted publickey for core from 10.0.0.1 port 52662 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:36.522559 sshd-session[1848]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:36.527429 systemd-logind[1525]: New session 2 of user core. Nov 1 09:59:36.538419 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 1 09:59:36.554108 sshd[1851]: Connection closed by 10.0.0.1 port 52662 Nov 1 09:59:36.554521 sshd-session[1848]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:36.575306 systemd[1]: sshd@1-10.0.0.18:22-10.0.0.1:52662.service: Deactivated successfully. Nov 1 09:59:36.577495 systemd[1]: session-2.scope: Deactivated successfully. Nov 1 09:59:36.578233 systemd-logind[1525]: Session 2 logged out. Waiting for processes to exit. Nov 1 09:59:36.581850 systemd[1]: Started sshd@2-10.0.0.18:22-10.0.0.1:52668.service - OpenSSH per-connection server daemon (10.0.0.1:52668). Nov 1 09:59:36.584995 systemd-logind[1525]: Removed session 2. Nov 1 09:59:36.637353 sshd[1857]: Accepted publickey for core from 10.0.0.1 port 52668 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:36.638682 sshd-session[1857]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:36.643421 systemd-logind[1525]: New session 3 of user core. Nov 1 09:59:36.653392 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 1 09:59:36.672349 sshd[1860]: Connection closed by 10.0.0.1 port 52668 Nov 1 09:59:36.672703 sshd-session[1857]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:36.677815 systemd[1]: sshd@2-10.0.0.18:22-10.0.0.1:52668.service: Deactivated successfully. Nov 1 09:59:36.680119 systemd[1]: session-3.scope: Deactivated successfully. Nov 1 09:59:36.681027 systemd-logind[1525]: Session 3 logged out. Waiting for processes to exit. Nov 1 09:59:36.682282 systemd-logind[1525]: Removed session 3. Nov 1 09:59:37.053972 dockerd[1649]: time="2025-11-01T09:59:37.053813025Z" level=info msg="Completed buildkit initialization" Nov 1 09:59:37.061546 dockerd[1649]: time="2025-11-01T09:59:37.061491903Z" level=info msg="Daemon has completed initialization" Nov 1 09:59:37.061715 dockerd[1649]: time="2025-11-01T09:59:37.061589438Z" level=info msg="API listen on /run/docker.sock" Nov 1 09:59:37.061817 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 1 09:59:37.064610 etcd-wrapper[1639]: Error response from daemon: No such container: etcd-member Nov 1 09:59:37.087343 etcd-wrapper[1884]: Error response from daemon: No such container: etcd-member Nov 1 09:59:37.143893 etcd-wrapper[1906]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally Nov 1 09:59:38.196825 etcd-wrapper[1906]: v3.5.22: Pulling from coreos/etcd Nov 1 09:59:38.437004 etcd-wrapper[1906]: f5083dc2076d: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: 2ae710cd8bfe: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: d462aa345367: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: 0f8b424aa0b9: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: d557676654e5: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: c8022d07192e: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: d858cbc252ad: Pulling fs layer Nov 1 09:59:38.437004 etcd-wrapper[1906]: 1069fc2daed1: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: b40161cd83fc: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: 5318d93a3a65: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: 307c1adadb60: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: 60a82e1b19c6: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: 6f77055ebb6e: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: 67f98bbc9874: Pulling fs layer Nov 1 09:59:38.437552 etcd-wrapper[1906]: c8022d07192e: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: d858cbc252ad: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 1069fc2daed1: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: b40161cd83fc: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 5318d93a3a65: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 307c1adadb60: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 60a82e1b19c6: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 6f77055ebb6e: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 67f98bbc9874: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: 0f8b424aa0b9: Waiting Nov 1 09:59:38.437552 etcd-wrapper[1906]: d557676654e5: Waiting Nov 1 09:59:38.654808 etcd-wrapper[1906]: 2ae710cd8bfe: Download complete Nov 1 09:59:38.664984 etcd-wrapper[1906]: f5083dc2076d: Download complete Nov 1 09:59:38.670895 etcd-wrapper[1906]: d462aa345367: Download complete Nov 1 09:59:38.709727 etcd-wrapper[1906]: f5083dc2076d: Pull complete Nov 1 09:59:38.813787 systemd[1]: var-lib-docker-overlay2-a5c184d385784d05440624c50655c17e2fb619193f70a28c64f7e2342029a51b-merged.mount: Deactivated successfully. Nov 1 09:59:38.848522 etcd-wrapper[1906]: 2ae710cd8bfe: Pull complete Nov 1 09:59:38.882652 etcd-wrapper[1906]: 0f8b424aa0b9: Download complete Nov 1 09:59:38.951290 etcd-wrapper[1906]: c8022d07192e: Verifying Checksum Nov 1 09:59:38.951290 etcd-wrapper[1906]: c8022d07192e: Download complete Nov 1 09:59:38.966518 etcd-wrapper[1906]: d557676654e5: Verifying Checksum Nov 1 09:59:38.966696 etcd-wrapper[1906]: d557676654e5: Download complete Nov 1 09:59:39.097612 etcd-wrapper[1906]: d858cbc252ad: Verifying Checksum Nov 1 09:59:39.097612 etcd-wrapper[1906]: d858cbc252ad: Download complete Nov 1 09:59:39.173716 etcd-wrapper[1906]: b40161cd83fc: Verifying Checksum Nov 1 09:59:39.173716 etcd-wrapper[1906]: b40161cd83fc: Download complete Nov 1 09:59:39.187078 etcd-wrapper[1906]: 1069fc2daed1: Download complete Nov 1 09:59:39.315355 etcd-wrapper[1906]: 5318d93a3a65: Verifying Checksum Nov 1 09:59:39.315355 etcd-wrapper[1906]: 5318d93a3a65: Download complete Nov 1 09:59:39.345311 systemd[1]: var-lib-docker-overlay2-6bfa96409bb6736abf1d6239f256c2f2bacc68a3e46ba818442caf9044206854-merged.mount: Deactivated successfully. Nov 1 09:59:39.391562 etcd-wrapper[1906]: 307c1adadb60: Verifying Checksum Nov 1 09:59:39.391562 etcd-wrapper[1906]: 307c1adadb60: Download complete Nov 1 09:59:39.407202 etcd-wrapper[1906]: d462aa345367: Pull complete Nov 1 09:59:39.425933 systemd[1]: var-lib-docker-overlay2-85c1ae18685c5a37b661511959f6782a4b544c930cb7de5025501a1b80b5edca-merged.mount: Deactivated successfully. Nov 1 09:59:39.455595 etcd-wrapper[1906]: 0f8b424aa0b9: Pull complete Nov 1 09:59:39.458167 etcd-wrapper[1906]: 60a82e1b19c6: Verifying Checksum Nov 1 09:59:39.458167 etcd-wrapper[1906]: 60a82e1b19c6: Download complete Nov 1 09:59:39.472636 systemd[1]: var-lib-docker-overlay2-dd50740bcf8a6fd2960b2b1e534a4175ac01977d2ed3b26e603a1903126dce02-merged.mount: Deactivated successfully. Nov 1 09:59:39.502600 etcd-wrapper[1906]: d557676654e5: Pull complete Nov 1 09:59:39.536591 etcd-wrapper[1906]: c8022d07192e: Pull complete Nov 1 09:59:39.577353 etcd-wrapper[1906]: 6f77055ebb6e: Verifying Checksum Nov 1 09:59:39.577353 etcd-wrapper[1906]: 6f77055ebb6e: Download complete Nov 1 09:59:39.582091 etcd-wrapper[1906]: d858cbc252ad: Pull complete Nov 1 09:59:39.621080 etcd-wrapper[1906]: 1069fc2daed1: Pull complete Nov 1 09:59:39.663596 etcd-wrapper[1906]: 67f98bbc9874: Verifying Checksum Nov 1 09:59:39.663596 etcd-wrapper[1906]: 67f98bbc9874: Download complete Nov 1 09:59:39.687248 etcd-wrapper[1906]: b40161cd83fc: Pull complete Nov 1 09:59:39.720431 etcd-wrapper[1906]: 5318d93a3a65: Pull complete Nov 1 09:59:39.755155 etcd-wrapper[1906]: 307c1adadb60: Pull complete Nov 1 09:59:40.036677 etcd-wrapper[1906]: 60a82e1b19c6: Pull complete Nov 1 09:59:40.250219 etcd-wrapper[1906]: 6f77055ebb6e: Pull complete Nov 1 09:59:40.353393 systemd[1]: var-lib-docker-overlay2-4942251848c5a8e8dfa87019017e7d400806da67334a3f8db07cb616f4df7ae6-merged.mount: Deactivated successfully. Nov 1 09:59:40.409586 etcd-wrapper[1906]: 67f98bbc9874: Pull complete Nov 1 09:59:40.419519 etcd-wrapper[1906]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6 Nov 1 09:59:40.422848 etcd-wrapper[1906]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22 Nov 1 09:59:40.435856 systemd[1]: var-lib-docker-overlay2-73f590bac458ed4db7ef1d7498b5c120de2c4461ef393bb23b7dbe7805f8cf8d\x2dinit-merged.mount: Deactivated successfully. Nov 1 09:59:40.486536 containerd[1549]: time="2025-11-01T09:59:40.486468005Z" level=info msg="connecting to shim 09f5ecea8f41b1a512c366755287f64f26eedfedeff789fd2ac896173e2c5791" address="unix:///run/containerd/s/1311ad5c4e328e1faad5ed46c658e6fe79b5570086808069d19a98f05bd4c441" namespace=moby protocol=ttrpc version=3 Nov 1 09:59:40.526395 systemd[1]: Started docker-09f5ecea8f41b1a512c366755287f64f26eedfedeff789fd2ac896173e2c5791.scope - libcontainer container 09f5ecea8f41b1a512c366755287f64f26eedfedeff789fd2ac896173e2c5791. Nov 1 09:59:40.688708 systemd-resolved[1308]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 1 09:59:40.757193 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.756574Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Nov 1 09:59:40.757193 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.756931Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"1406e6af5093484a8b02a3517c282912"} Nov 1 09:59:40.757193 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.756958Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Nov 1 09:59:40.757193 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.756972Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"} Nov 1 09:59:40.757193 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.756977Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Nov 1 09:59:40.757193 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.756982Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Nov 1 09:59:40.757873 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.757150Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 1 09:59:40.757873 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.757198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.18:2379"]} Nov 1 09:59:40.757873 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.757392Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 1 09:59:40.757873 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.757422Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Nov 1 09:59:40.759069 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.758814Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Nov 1 09:59:40.759406 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.759130Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.22","git-sha":"7862c85","go-version":"go1.23.11","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"1406e6af5093484a8b02a3517c282912","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.18:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"1406e6af5093484a8b02a3517c282912=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Nov 1 09:59:40.764084 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.763196Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.542428ms"} Nov 1 09:59:40.769017 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.768769Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Nov 1 09:59:40.769131 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.768840Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Nov 1 09:59:40.769131 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.768917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Nov 1 09:59:40.769131 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.768930Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Nov 1 09:59:40.769131 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.768942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Nov 1 09:59:40.769131 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.768985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 1 09:59:40.774259 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T09:59:40.773932Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Nov 1 09:59:40.775282 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.775030Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Nov 1 09:59:40.775282 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.775061Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Nov 1 09:59:40.776221 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.775992Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Nov 1 09:59:40.777482 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.776931Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.22","cluster-version":"to_be_decided"} Nov 1 09:59:40.777758 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.777503Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 1 09:59:40.779213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.777625Z","caller":"etcdserver/server.go:759","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Nov 1 09:59:40.779213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.777697Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Nov 1 09:59:40.779213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.778538Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Nov 1 09:59:40.779213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.778694Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Nov 1 09:59:40.780124 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.779830Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 1 09:59:40.780442 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.780144Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"],"added-peer-is-learner":false} Nov 1 09:59:40.782363 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.782109Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Nov 1 09:59:40.782363 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.782156Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Nov 1 09:59:40.783612 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:40.783422Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.18:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Nov 1 09:59:41.670494 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.669938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Nov 1 09:59:41.670494 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.670052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Nov 1 09:59:41.670494 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.670110Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Nov 1 09:59:41.671107 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.670132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Nov 1 09:59:41.671107 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.670270Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Nov 1 09:59:41.671107 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.670284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Nov 1 09:59:41.671107 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.670292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Nov 1 09:59:41.671551 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.671363Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Nov 1 09:59:41.672354 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672006Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:1406e6af5093484a8b02a3517c282912 ClientURLs:[http://10.0.0.18:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Nov 1 09:59:41.672354 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672064Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Nov 1 09:59:41.672672 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672453Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Nov 1 09:59:41.672672 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672535Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Nov 1 09:59:41.672672 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672555Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} Nov 1 09:59:41.674707 systemd[1]: Started etcd-member.service - etcd (System Application Container). Nov 1 09:59:41.675140 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 1 09:59:41.682473 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672463Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Nov 1 09:59:41.682473 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.672877Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 1 09:59:41.682473 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.674376Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Nov 1 09:59:41.699079 systemd[1]: Startup finished in 3.721s (kernel) + 4.971s (initrd) + 12.693s (userspace) = 21.386s. Nov 1 09:59:41.699590 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T09:59:41.699143Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Nov 1 09:59:46.825277 systemd[1]: Started sshd@3-10.0.0.18:22-10.0.0.1:59026.service - OpenSSH per-connection server daemon (10.0.0.1:59026). Nov 1 09:59:46.893508 sshd[2049]: Accepted publickey for core from 10.0.0.1 port 59026 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:46.895010 sshd-session[2049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:46.899676 systemd-logind[1525]: New session 4 of user core. Nov 1 09:59:46.914372 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 1 09:59:46.927762 sshd[2052]: Connection closed by 10.0.0.1 port 59026 Nov 1 09:59:46.928128 sshd-session[2049]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:46.938609 systemd[1]: sshd@3-10.0.0.18:22-10.0.0.1:59026.service: Deactivated successfully. Nov 1 09:59:46.940338 systemd[1]: session-4.scope: Deactivated successfully. Nov 1 09:59:46.941084 systemd-logind[1525]: Session 4 logged out. Waiting for processes to exit. Nov 1 09:59:46.943821 systemd[1]: Started sshd@4-10.0.0.18:22-10.0.0.1:59028.service - OpenSSH per-connection server daemon (10.0.0.1:59028). Nov 1 09:59:46.944407 systemd-logind[1525]: Removed session 4. Nov 1 09:59:47.001904 sshd[2058]: Accepted publickey for core from 10.0.0.1 port 59028 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:47.003270 sshd-session[2058]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:47.007872 systemd-logind[1525]: New session 5 of user core. Nov 1 09:59:47.018366 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 1 09:59:47.027743 sshd[2061]: Connection closed by 10.0.0.1 port 59028 Nov 1 09:59:47.028042 sshd-session[2058]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:47.040833 systemd[1]: sshd@4-10.0.0.18:22-10.0.0.1:59028.service: Deactivated successfully. Nov 1 09:59:47.042612 systemd[1]: session-5.scope: Deactivated successfully. Nov 1 09:59:47.043362 systemd-logind[1525]: Session 5 logged out. Waiting for processes to exit. Nov 1 09:59:47.046318 systemd[1]: Started sshd@5-10.0.0.18:22-10.0.0.1:59036.service - OpenSSH per-connection server daemon (10.0.0.1:59036). Nov 1 09:59:47.046902 systemd-logind[1525]: Removed session 5. Nov 1 09:59:47.109843 sshd[2067]: Accepted publickey for core from 10.0.0.1 port 59036 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:47.111422 sshd-session[2067]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:47.115878 systemd-logind[1525]: New session 6 of user core. Nov 1 09:59:47.127398 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 1 09:59:47.141244 sshd[2071]: Connection closed by 10.0.0.1 port 59036 Nov 1 09:59:47.141524 sshd-session[2067]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:47.156029 systemd[1]: sshd@5-10.0.0.18:22-10.0.0.1:59036.service: Deactivated successfully. Nov 1 09:59:47.157936 systemd[1]: session-6.scope: Deactivated successfully. Nov 1 09:59:47.158703 systemd-logind[1525]: Session 6 logged out. Waiting for processes to exit. Nov 1 09:59:47.161668 systemd[1]: Started sshd@6-10.0.0.18:22-10.0.0.1:59052.service - OpenSSH per-connection server daemon (10.0.0.1:59052). Nov 1 09:59:47.162435 systemd-logind[1525]: Removed session 6. Nov 1 09:59:47.229460 sshd[2077]: Accepted publickey for core from 10.0.0.1 port 59052 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:47.231024 sshd-session[2077]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:47.235776 systemd-logind[1525]: New session 7 of user core. Nov 1 09:59:47.249372 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 1 09:59:47.271823 sudo[2081]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 1 09:59:47.272157 sudo[2081]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 1 09:59:47.290043 sudo[2081]: pam_unix(sudo:session): session closed for user root Nov 1 09:59:47.292229 sshd[2080]: Connection closed by 10.0.0.1 port 59052 Nov 1 09:59:47.292756 sshd-session[2077]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:47.303589 systemd[1]: sshd@6-10.0.0.18:22-10.0.0.1:59052.service: Deactivated successfully. Nov 1 09:59:47.305721 systemd[1]: session-7.scope: Deactivated successfully. Nov 1 09:59:47.306620 systemd-logind[1525]: Session 7 logged out. Waiting for processes to exit. Nov 1 09:59:47.310202 systemd[1]: Started sshd@7-10.0.0.18:22-10.0.0.1:59054.service - OpenSSH per-connection server daemon (10.0.0.1:59054). Nov 1 09:59:47.310833 systemd-logind[1525]: Removed session 7. Nov 1 09:59:47.371765 sshd[2087]: Accepted publickey for core from 10.0.0.1 port 59054 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:47.373152 sshd-session[2087]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:47.377575 systemd-logind[1525]: New session 8 of user core. Nov 1 09:59:47.393369 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 1 09:59:47.408004 sudo[2092]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 1 09:59:47.408369 sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 1 09:59:47.414829 sudo[2092]: pam_unix(sudo:session): session closed for user root Nov 1 09:59:47.422698 sudo[2091]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 1 09:59:47.423026 sudo[2091]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 1 09:59:47.433609 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 1 09:59:47.490513 augenrules[2114]: No rules Nov 1 09:59:47.492146 systemd[1]: audit-rules.service: Deactivated successfully. Nov 1 09:59:47.492508 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 1 09:59:47.493629 sudo[2091]: pam_unix(sudo:session): session closed for user root Nov 1 09:59:47.495399 sshd[2090]: Connection closed by 10.0.0.1 port 59054 Nov 1 09:59:47.495740 sshd-session[2087]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:47.508920 systemd[1]: sshd@7-10.0.0.18:22-10.0.0.1:59054.service: Deactivated successfully. Nov 1 09:59:47.510769 systemd[1]: session-8.scope: Deactivated successfully. Nov 1 09:59:47.511492 systemd-logind[1525]: Session 8 logged out. Waiting for processes to exit. Nov 1 09:59:47.514090 systemd[1]: Started sshd@8-10.0.0.18:22-10.0.0.1:59058.service - OpenSSH per-connection server daemon (10.0.0.1:59058). Nov 1 09:59:47.514737 systemd-logind[1525]: Removed session 8. Nov 1 09:59:47.579524 sshd[2123]: Accepted publickey for core from 10.0.0.1 port 59058 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:47.581322 sshd-session[2123]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:47.586420 systemd-logind[1525]: New session 9 of user core. Nov 1 09:59:47.596387 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 1 09:59:47.606720 sshd[2126]: Connection closed by 10.0.0.1 port 59058 Nov 1 09:59:47.607034 sshd-session[2123]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:47.622127 systemd[1]: sshd@8-10.0.0.18:22-10.0.0.1:59058.service: Deactivated successfully. Nov 1 09:59:47.624098 systemd[1]: session-9.scope: Deactivated successfully. Nov 1 09:59:47.624887 systemd-logind[1525]: Session 9 logged out. Waiting for processes to exit. Nov 1 09:59:47.628002 systemd[1]: Started sshd@9-10.0.0.18:22-10.0.0.1:59066.service - OpenSSH per-connection server daemon (10.0.0.1:59066). Nov 1 09:59:47.628551 systemd-logind[1525]: Removed session 9. Nov 1 09:59:47.694333 sshd[2134]: Accepted publickey for core from 10.0.0.1 port 59066 ssh2: RSA SHA256:xyHlhP/ZWauU1qF16e0XO1liGu774KWQKuYesmG87DE Nov 1 09:59:47.695815 sshd-session[2134]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 09:59:47.700794 systemd-logind[1525]: New session 10 of user core. Nov 1 09:59:47.811605 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 1 09:59:47.905508 sshd[2137]: Connection closed by 10.0.0.1 port 59066 Nov 1 09:59:47.906459 sshd-session[2134]: pam_unix(sshd:session): session closed for user core Nov 1 09:59:47.911655 systemd[1]: sshd@9-10.0.0.18:22-10.0.0.1:59066.service: Deactivated successfully. Nov 1 09:59:47.913580 systemd[1]: session-10.scope: Deactivated successfully. Nov 1 09:59:47.914309 systemd-logind[1525]: Session 10 logged out. Waiting for processes to exit. Nov 1 09:59:47.915411 systemd-logind[1525]: Removed session 10. Nov 1 10:00:17.319583 update_engine[1531]: I20251101 10:00:17.319382 1531 update_attempter.cc:509] Updating boot flags... Nov 1 10:00:38.652206 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:38.651732Z","caller":"traceutil/trace.go:171","msg":"trace[722930408] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"176.824965ms","start":"2025-11-01T10:00:38.474869Z","end":"2025-11-01T10:00:38.651694Z","steps":["trace[722930408] 'process raft request' (duration: 141.857029ms)","trace[722930408] 'compare' (duration: 34.853209ms)"],"step_count":2} Nov 1 10:00:38.827323 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:38.827088Z","caller":"traceutil/trace.go:171","msg":"trace[594036749] linearizableReadLoop","detail":"{readStateIndex:242; appliedIndex:241; }","duration":"113.456734ms","start":"2025-11-01T10:00:38.713614Z","end":"2025-11-01T10:00:38.827071Z","steps":["trace[594036749] 'read index received' (duration: 54.411653ms)","trace[594036749] 'applied index is now lower than readState.Index' (duration: 59.044781ms)"],"step_count":2} Nov 1 10:00:38.827323 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:38.827102Z","caller":"traceutil/trace.go:171","msg":"trace[11890996] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"113.927218ms","start":"2025-11-01T10:00:38.713164Z","end":"2025-11-01T10:00:38.827091Z","steps":["trace[11890996] 'process raft request' (duration: 54.845982ms)","trace[11890996] 'compare' (duration: 59.015209ms)"],"step_count":2} Nov 1 10:00:38.827479 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:38.827169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.531638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:validatingadmissionpolicy-status-controller\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:00:38.827479 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:38.827201Z","caller":"traceutil/trace.go:171","msg":"trace[2031811181] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:validatingadmissionpolicy-status-controller; range_end:; response_count:0; response_revision:236; }","duration":"113.580791ms","start":"2025-11-01T10:00:38.713608Z","end":"2025-11-01T10:00:38.827188Z","steps":["trace[2031811181] 'agreement among raft nodes before linearized reading' (duration: 113.515285ms)"],"step_count":1} Nov 1 10:00:40.991179 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:40.990609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.484353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Nov 1 10:00:40.991179 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:40.990750Z","caller":"traceutil/trace.go:171","msg":"trace[755886929] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"129.951441ms","start":"2025-11-01T10:00:40.860782Z","end":"2025-11-01T10:00:40.990733Z","steps":["trace[755886929] 'read index received' (duration: 1.708968ms)","trace[755886929] 'applied index is now lower than readState.Index' (duration: 128.241591ms)"],"step_count":2} Nov 1 10:00:40.991179 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:40.990801Z","caller":"traceutil/trace.go:171","msg":"trace[1053533017] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"185.527339ms","start":"2025-11-01T10:00:40.805241Z","end":"2025-11-01T10:00:40.990769Z","steps":["trace[1053533017] 'process raft request' (duration: 57.289616ms)","trace[1053533017] 'compare' (duration: 127.371921ms)"],"step_count":2} Nov 1 10:00:40.991179 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:40.990972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.146973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 1 10:00:40.991921 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:40.991040Z","caller":"traceutil/trace.go:171","msg":"trace[1237996247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:302; }","duration":"130.250727ms","start":"2025-11-01T10:00:40.860776Z","end":"2025-11-01T10:00:40.991027Z","steps":["trace[1237996247] 'agreement among raft nodes before linearized reading' (duration: 130.034292ms)"],"step_count":1} Nov 1 10:00:46.044836 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:46.044445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.403542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" limit:1 ","response":"range_response_count:1 size:214"} Nov 1 10:00:46.044836 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:46.044517Z","caller":"traceutil/trace.go:171","msg":"trace[1978678911] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:377; }","duration":"119.477331ms","start":"2025-11-01T10:00:45.925021Z","end":"2025-11-01T10:00:46.044499Z","steps":["trace[1978678911] 'range keys from in-memory index tree' (duration: 119.346846ms)"],"step_count":1} Nov 1 10:00:47.722587 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:47.722136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.240392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Nov 1 10:00:47.722587 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:47.722276Z","caller":"traceutil/trace.go:171","msg":"trace[2046982411] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"151.065708ms","start":"2025-11-01T10:00:47.571187Z","end":"2025-11-01T10:00:47.722252Z","steps":["trace[2046982411] 'process raft request' (duration: 47.666702ms)","trace[2046982411] 'compare' (duration: 103.153528ms)"],"step_count":2} Nov 1 10:00:49.263946 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:49.263555Z","caller":"traceutil/trace.go:171","msg":"trace[1038221703] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"258.711165ms","start":"2025-11-01T10:00:49.004823Z","end":"2025-11-01T10:00:49.263534Z","steps":["trace[1038221703] 'process raft request' (duration: 245.575884ms)","trace[1038221703] 'compare' (duration: 13.052394ms)"],"step_count":2} Nov 1 10:00:49.277828 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:49.277594Z","caller":"traceutil/trace.go:171","msg":"trace[1646715178] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"173.653046ms","start":"2025-11-01T10:00:49.103930Z","end":"2025-11-01T10:00:49.277583Z","steps":["trace[1646715178] 'process raft request' (duration: 173.550859ms)"],"step_count":1} Nov 1 10:00:52.974009 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:52.973469Z","caller":"traceutil/trace.go:171","msg":"trace[1631636340] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"122.62706ms","start":"2025-11-01T10:00:52.850812Z","end":"2025-11-01T10:00:52.973439Z","steps":["trace[1631636340] 'process raft request' (duration: 122.472879ms)"],"step_count":1} Nov 1 10:00:53.353621 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:53.353069Z","caller":"traceutil/trace.go:171","msg":"trace[876235318] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"156.082283ms","start":"2025-11-01T10:00:53.196964Z","end":"2025-11-01T10:00:53.353046Z","steps":["trace[876235318] 'read index received' (duration: 155.988224ms)","trace[876235318] 'applied index is now lower than readState.Index' (duration: 93.569µs)"],"step_count":2} Nov 1 10:00:53.353621 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:53.353087Z","caller":"traceutil/trace.go:171","msg":"trace[2027469120] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"303.088307ms","start":"2025-11-01T10:00:53.049980Z","end":"2025-11-01T10:00:53.353069Z","steps":["trace[2027469120] 'process raft request' (duration: 302.92544ms)"],"step_count":1} Nov 1 10:00:53.353621 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:53.353297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.32861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io\" limit:1 ","response":"range_response_count:1 size:61135"} Nov 1 10:00:53.353621 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:53.353357Z","caller":"traceutil/trace.go:171","msg":"trace[1963337676] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io; range_end:; response_count:1; response_revision:563; }","duration":"156.371385ms","start":"2025-11-01T10:00:53.196954Z","end":"2025-11-01T10:00:53.353326Z","steps":["trace[1963337676] 'agreement among raft nodes before linearized reading' (duration: 156.197605ms)"],"step_count":1} Nov 1 10:00:53.354003 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:53.353390Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-01T10:00:53.049964Z","time spent":"303.185393ms","remote":"10.0.0.25:56844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56995,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 1 10:00:53.435697 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:53.435296Z","caller":"traceutil/trace.go:171","msg":"trace[444147212] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"148.748131ms","start":"2025-11-01T10:00:53.286523Z","end":"2025-11-01T10:00:53.435271Z","steps":["trace[444147212] 'process raft request' (duration: 136.299286ms)","trace[444147212] 'compare' (duration: 12.318613ms)"],"step_count":2} Nov 1 10:00:56.093876 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:56.093545Z","caller":"traceutil/trace.go:171","msg":"trace[815713427] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"139.792135ms","start":"2025-11-01T10:00:55.953736Z","end":"2025-11-01T10:00:56.093528Z","steps":["trace[815713427] 'process raft request' (duration: 139.693257ms)"],"step_count":1} Nov 1 10:00:56.254050 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:56.253669Z","caller":"traceutil/trace.go:171","msg":"trace[1678651227] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"105.342882ms","start":"2025-11-01T10:00:56.148307Z","end":"2025-11-01T10:00:56.253650Z","steps":["trace[1678651227] 'read index received' (duration: 103.689715ms)","trace[1678651227] 'applied index is now lower than readState.Index' (duration: 1.652466ms)"],"step_count":2} Nov 1 10:00:56.254050 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:56.253696Z","caller":"traceutil/trace.go:171","msg":"trace[426454388] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"151.708532ms","start":"2025-11-01T10:00:56.101965Z","end":"2025-11-01T10:00:56.253674Z","steps":["trace[426454388] 'process raft request' (duration: 150.022138ms)"],"step_count":1} Nov 1 10:00:56.254050 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:56.253789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.456089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:2977"} Nov 1 10:00:56.254050 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:56.253800Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.590545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:2977"} Nov 1 10:00:56.254050 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:56.253814Z","caller":"traceutil/trace.go:171","msg":"trace[1801137577] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:575; }","duration":"150.495899ms","start":"2025-11-01T10:00:56.103308Z","end":"2025-11-01T10:00:56.253804Z","steps":["trace[1801137577] 'agreement among raft nodes before linearized reading' (duration: 150.4415ms)"],"step_count":1} Nov 1 10:00:56.254400 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:56.253831Z","caller":"traceutil/trace.go:171","msg":"trace[969650595] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:575; }","duration":"142.636538ms","start":"2025-11-01T10:00:56.111187Z","end":"2025-11-01T10:00:56.253824Z","steps":["trace[969650595] 'agreement among raft nodes before linearized reading' (duration: 142.509323ms)"],"step_count":1} Nov 1 10:00:56.274002 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:56.273552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.478947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/goldmanes/default\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:00:56.274002 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:56.273644Z","caller":"traceutil/trace.go:171","msg":"trace[1490991184] range","detail":"{range_begin:/registry/operator.tigera.io/goldmanes/default; range_end:; response_count:0; response_revision:576; }","duration":"123.562173ms","start":"2025-11-01T10:00:56.150038Z","end":"2025-11-01T10:00:56.273600Z","steps":["trace[1490991184] 'agreement among raft nodes before linearized reading' (duration: 123.372804ms)"],"step_count":1} Nov 1 10:00:58.106910 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.704247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/whiskers/default\" limit:1 ","response":"range_response_count:1 size:948"} Nov 1 10:00:58.106910 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.745788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-tiered-policy-passthrough\" limit:1 ","response":"range_response_count:1 size:638"} Nov 1 10:00:58.106910 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.764691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:452"} Nov 1 10:00:58.106910 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106250Z","caller":"traceutil/trace.go:171","msg":"trace[1212476559] range","detail":"{range_begin:/registry/clusterroles/calico-tiered-policy-passthrough; range_end:; response_count:1; response_revision:639; }","duration":"323.777853ms","start":"2025-11-01T10:00:57.782445Z","end":"2025-11-01T10:00:58.106223Z","steps":["trace[1212476559] 'range keys from in-memory index tree' (duration: 323.703795ms)"],"step_count":1} Nov 1 10:00:58.106910 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106180Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.739072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/calico-apiserver/tigera-operator-secrets\" limit:1 ","response":"range_response_count:1 size:666"} Nov 1 10:00:58.107774 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106280Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-01T10:00:57.782439Z","time spent":"323.832292ms","remote":"10.0.0.25:57120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":662,"request content":"key:\"/registry/clusterroles/calico-tiered-policy-passthrough\" limit:1 "} Nov 1 10:00:58.107774 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106283Z","caller":"traceutil/trace.go:171","msg":"trace[1829639902] range","detail":"{range_begin:/registry/rolebindings/calico-apiserver/tigera-operator-secrets; range_end:; response_count:1; response_revision:639; }","duration":"136.842399ms","start":"2025-11-01T10:00:57.969431Z","end":"2025-11-01T10:00:58.106273Z","steps":["trace[1829639902] 'range keys from in-memory index tree' (duration: 136.653921ms)"],"step_count":1} Nov 1 10:00:58.107774 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106256Z","caller":"traceutil/trace.go:171","msg":"trace[1170417844] range","detail":"{range_begin:/registry/serviceaccounts/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:639; }","duration":"111.802607ms","start":"2025-11-01T10:00:57.994448Z","end":"2025-11-01T10:00:58.106250Z","steps":["trace[1170417844] 'range keys from in-memory index tree' (duration: 111.695692ms)"],"step_count":1} Nov 1 10:00:58.107774 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.210585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3446"} Nov 1 10:00:58.108123 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106371Z","caller":"traceutil/trace.go:171","msg":"trace[1072132277] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:639; }","duration":"232.30274ms","start":"2025-11-01T10:00:57.874056Z","end":"2025-11-01T10:00:58.106358Z","steps":["trace[1072132277] 'range keys from in-memory index tree' (duration: 232.087569ms)"],"step_count":1} Nov 1 10:00:58.108123 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106380Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.382196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/calico-apiserver-auth-reader\" limit:1 ","response":"range_response_count:1 size:679"} Nov 1 10:00:58.108123 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.211806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/calico-apiserver-certs\" limit:1 ","response":"range_response_count:1 size:4751"} Nov 1 10:00:58.108123 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106413Z","caller":"traceutil/trace.go:171","msg":"trace[2030047080] range","detail":"{range_begin:/registry/rolebindings/kube-system/calico-apiserver-auth-reader; range_end:; response_count:1; response_revision:639; }","duration":"211.414622ms","start":"2025-11-01T10:00:57.894989Z","end":"2025-11-01T10:00:58.106404Z","steps":["trace[2030047080] 'range keys from in-memory index tree' (duration: 211.333469ms)"],"step_count":1} Nov 1 10:00:58.108300 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106424Z","caller":"traceutil/trace.go:171","msg":"trace[1358733986] range","detail":"{range_begin:/registry/secrets/tigera-operator/calico-apiserver-certs; range_end:; response_count:1; response_revision:639; }","duration":"246.481977ms","start":"2025-11-01T10:00:57.859934Z","end":"2025-11-01T10:00:58.106416Z","steps":["trace[1358733986] 'range keys from in-memory index tree' (duration: 246.162639ms)"],"step_count":1} Nov 1 10:00:58.108300 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106223Z","caller":"traceutil/trace.go:171","msg":"trace[1478927393] range","detail":"{range_begin:/registry/operator.tigera.io/whiskers/default; range_end:; response_count:1; response_revision:639; }","duration":"139.789577ms","start":"2025-11-01T10:00:57.966420Z","end":"2025-11-01T10:00:58.106209Z","steps":["trace[1478927393] 'range keys from in-memory index tree' (duration: 139.610989ms)"],"step_count":1} Nov 1 10:00:58.108300 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.106388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.071785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/installations.operator.tigera.io\" limit:1 ","response":"range_response_count:1 size:630504"} Nov 1 10:00:58.108300 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.106490Z","caller":"traceutil/trace.go:171","msg":"trace[2003622880] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/installations.operator.tigera.io; range_end:; response_count:1; response_revision:639; }","duration":"105.171344ms","start":"2025-11-01T10:00:58.001309Z","end":"2025-11-01T10:00:58.106481Z","steps":["trace[2003622880] 'range keys from in-memory index tree' (duration: 104.897095ms)"],"step_count":1} Nov 1 10:00:58.673943 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.673303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.895065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/tigerastatuses/management-cluster-connection\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:00:58.673943 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.673365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.440225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/calico-system\" limit:1 ","response":"range_response_count:1 size:764"} Nov 1 10:00:58.673943 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.673399Z","caller":"traceutil/trace.go:171","msg":"trace[915734808] range","detail":"{range_begin:/registry/operator.tigera.io/tigerastatuses/management-cluster-connection; range_end:; response_count:0; response_revision:652; }","duration":"122.00228ms","start":"2025-11-01T10:00:58.551380Z","end":"2025-11-01T10:00:58.673383Z","steps":["trace[915734808] 'agreement among raft nodes before linearized reading' (duration: 121.866819ms)"],"step_count":1} Nov 1 10:00:58.673943 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.673416Z","caller":"traceutil/trace.go:171","msg":"trace[1419528003] range","detail":"{range_begin:/registry/namespaces/calico-system; range_end:; response_count:1; response_revision:652; }","duration":"115.501437ms","start":"2025-11-01T10:00:58.557903Z","end":"2025-11-01T10:00:58.673405Z","steps":["trace[1419528003] 'agreement among raft nodes before linearized reading' (duration: 115.318982ms)"],"step_count":1} Nov 1 10:00:58.673943 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.673440Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.063643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/tigerastatuses/gatewayapi\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:00:58.674306 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.673468Z","caller":"traceutil/trace.go:171","msg":"trace[198764419] range","detail":"{range_begin:/registry/operator.tigera.io/tigerastatuses/gatewayapi; range_end:; response_count:0; response_revision:652; }","duration":"122.094223ms","start":"2025-11-01T10:00:58.551366Z","end":"2025-11-01T10:00:58.673461Z","steps":["trace[198764419] 'agreement among raft nodes before linearized reading' (duration: 122.051919ms)"],"step_count":1} Nov 1 10:00:58.674306 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.673528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.171317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/tigerastatuses/calico-windows\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:00:58.674306 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.673554Z","caller":"traceutil/trace.go:171","msg":"trace[2063738284] range","detail":"{range_begin:/registry/operator.tigera.io/tigerastatuses/calico-windows; range_end:; response_count:0; response_revision:652; }","duration":"122.198503ms","start":"2025-11-01T10:00:58.551350Z","end":"2025-11-01T10:00:58.673548Z","steps":["trace[2063738284] 'agreement among raft nodes before linearized reading' (duration: 122.128753ms)"],"step_count":1} Nov 1 10:00:58.674306 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:00:58.673550Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.180231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/calico-system/tigera-operator-secrets\" limit:1 ","response":"range_response_count:1 size:663"} Nov 1 10:00:58.674435 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:00:58.673796Z","caller":"traceutil/trace.go:171","msg":"trace[184907605] range","detail":"{range_begin:/registry/rolebindings/calico-system/tigera-operator-secrets; range_end:; response_count:1; response_revision:652; }","duration":"114.296745ms","start":"2025-11-01T10:00:58.559322Z","end":"2025-11-01T10:00:58.673619Z","steps":["trace[184907605] 'agreement among raft nodes before linearized reading' (duration: 114.02477ms)"],"step_count":1} Nov 1 10:01:01.669766 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:01.669359Z","caller":"traceutil/trace.go:171","msg":"trace[1685385275] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"122.81791ms","start":"2025-11-01T10:01:01.546495Z","end":"2025-11-01T10:01:01.669313Z","steps":["trace[1685385275] 'process raft request' (duration: 64.244758ms)","trace[1685385275] 'compare' (duration: 58.45743ms)"],"step_count":2} Nov 1 10:01:01.871636 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:01.871018Z","caller":"traceutil/trace.go:171","msg":"trace[1099290180] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"146.420535ms","start":"2025-11-01T10:01:01.724580Z","end":"2025-11-01T10:01:01.871000Z","steps":["trace[1099290180] 'read index received' (duration: 142.406932ms)","trace[1099290180] 'applied index is now lower than readState.Index' (duration: 4.013111ms)"],"step_count":2} Nov 1 10:01:01.871636 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:01.871045Z","caller":"traceutil/trace.go:171","msg":"trace[1872882893] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"170.536005ms","start":"2025-11-01T10:01:01.700499Z","end":"2025-11-01T10:01:01.871035Z","steps":["trace[1872882893] 'process raft request' (duration: 166.518214ms)"],"step_count":1} Nov 1 10:01:01.871636 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:01.871161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.5733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-tier-getter\" limit:1 ","response":"range_response_count:1 size:647"} Nov 1 10:01:01.871636 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:01.871194Z","caller":"traceutil/trace.go:171","msg":"trace[635459253] range","detail":"{range_begin:/registry/clusterrolebindings/calico-tier-getter; range_end:; response_count:1; response_revision:793; }","duration":"146.620695ms","start":"2025-11-01T10:01:01.724565Z","end":"2025-11-01T10:01:01.871186Z","steps":["trace[635459253] 'agreement among raft nodes before linearized reading' (duration: 146.49857ms)"],"step_count":1} Nov 1 10:01:04.482838 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:04.482502Z","caller":"traceutil/trace.go:171","msg":"trace[700130856] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"103.701963ms","start":"2025-11-01T10:01:04.378785Z","end":"2025-11-01T10:01:04.482487Z","steps":["trace[700130856] 'read index received' (duration: 18.850688ms)","trace[700130856] 'applied index is now lower than readState.Index' (duration: 84.850884ms)"],"step_count":2} Nov 1 10:01:04.482838 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:04.482622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.821692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/calico-system/calico-kube-controllers\" limit:1 ","response":"range_response_count:1 size:5165"} Nov 1 10:01:04.482838 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:04.482653Z","caller":"traceutil/trace.go:171","msg":"trace[1470583008] range","detail":"{range_begin:/registry/deployments/calico-system/calico-kube-controllers; range_end:; response_count:1; response_revision:808; }","duration":"103.858494ms","start":"2025-11-01T10:01:04.378782Z","end":"2025-11-01T10:01:04.482640Z","steps":["trace[1470583008] 'agreement among raft nodes before linearized reading' (duration: 103.788485ms)"],"step_count":1} Nov 1 10:01:05.797931 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.797561Z","caller":"traceutil/trace.go:171","msg":"trace[1152450147] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"180.85223ms","start":"2025-11-01T10:01:05.616691Z","end":"2025-11-01T10:01:05.797544Z","steps":["trace[1152450147] 'read index received' (duration: 139.634442ms)","trace[1152450147] 'applied index is now lower than readState.Index' (duration: 41.217417ms)"],"step_count":2} Nov 1 10:01:05.797931 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.797618Z","caller":"traceutil/trace.go:171","msg":"trace[666897353] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"180.941899ms","start":"2025-11-01T10:01:05.616658Z","end":"2025-11-01T10:01:05.797600Z","steps":["trace[666897353] 'process raft request' (duration: 139.679501ms)","trace[666897353] 'compare' (duration: 41.113451ms)"],"step_count":2} Nov 1 10:01:05.798577 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:05.797799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.07705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/whisker-698b695488-phsz5\" limit:1 ","response":"range_response_count:1 size:4848"} Nov 1 10:01:05.798577 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.797878Z","caller":"traceutil/trace.go:171","msg":"trace[1102212181] range","detail":"{range_begin:/registry/pods/calico-system/whisker-698b695488-phsz5; range_end:; response_count:1; response_revision:822; }","duration":"181.17247ms","start":"2025-11-01T10:01:05.616689Z","end":"2025-11-01T10:01:05.797862Z","steps":["trace[1102212181] 'agreement among raft nodes before linearized reading' (duration: 180.929666ms)"],"step_count":1} Nov 1 10:01:05.801012 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.800462Z","caller":"traceutil/trace.go:171","msg":"trace[36130632] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"181.748408ms","start":"2025-11-01T10:01:05.618702Z","end":"2025-11-01T10:01:05.800451Z","steps":["trace[36130632] 'process raft request' (duration: 181.634851ms)"],"step_count":1} Nov 1 10:01:05.801012 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:05.800555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.442604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/tigera-apiserver\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:01:05.801012 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.800563Z","caller":"traceutil/trace.go:171","msg":"trace[1193727590] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"123.455371ms","start":"2025-11-01T10:01:05.677093Z","end":"2025-11-01T10:01:05.800548Z","steps":["trace[1193727590] 'process raft request' (duration: 123.306895ms)"],"step_count":1} Nov 1 10:01:05.801012 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.800580Z","caller":"traceutil/trace.go:171","msg":"trace[2060253722] range","detail":"{range_begin:/registry/clusterrolebindings/tigera-apiserver; range_end:; response_count:0; response_revision:824; }","duration":"175.475929ms","start":"2025-11-01T10:01:05.625097Z","end":"2025-11-01T10:01:05.800573Z","steps":["trace[2060253722] 'agreement among raft nodes before linearized reading' (duration: 175.423826ms)"],"step_count":1} Nov 1 10:01:05.801012 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:05.800628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.031612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/whiskers/default\" limit:1 ","response":"range_response_count:1 size:948"} Nov 1 10:01:05.801258 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:05.800648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.832819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/goldmane-ca-bundle\" limit:1 ","response":"range_response_count:1 size:1959"} Nov 1 10:01:05.801258 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.800659Z","caller":"traceutil/trace.go:171","msg":"trace[1580947068] range","detail":"{range_begin:/registry/operator.tigera.io/whiskers/default; range_end:; response_count:1; response_revision:824; }","duration":"167.062505ms","start":"2025-11-01T10:01:05.633588Z","end":"2025-11-01T10:01:05.800651Z","steps":["trace[1580947068] 'agreement among raft nodes before linearized reading' (duration: 166.971954ms)"],"step_count":1} Nov 1 10:01:05.801258 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:05.800673Z","caller":"traceutil/trace.go:171","msg":"trace[1059701586] range","detail":"{range_begin:/registry/configmaps/calico-system/goldmane-ca-bundle; range_end:; response_count:1; response_revision:824; }","duration":"172.859372ms","start":"2025-11-01T10:01:05.627807Z","end":"2025-11-01T10:01:05.800666Z","steps":["trace[1059701586] 'agreement among raft nodes before linearized reading' (duration: 172.756007ms)"],"step_count":1} Nov 1 10:01:21.632507 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:21.631978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.073611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/calico-system/calico-node-d6gqr.1873d9b76b6a298d\" limit:1 ","response":"range_response_count:1 size:809"} Nov 1 10:01:21.632507 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:21.632078Z","caller":"traceutil/trace.go:171","msg":"trace[2058351868] range","detail":"{range_begin:/registry/events/calico-system/calico-node-d6gqr.1873d9b76b6a298d; range_end:; response_count:1; response_revision:953; }","duration":"125.188545ms","start":"2025-11-01T10:01:21.506870Z","end":"2025-11-01T10:01:21.632059Z","steps":["trace[2058351868] 'range keys from in-memory index tree' (duration: 124.898976ms)"],"step_count":1} Nov 1 10:01:21.632507 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:21.632007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.11037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/whisker-69576fcb57-d9hxp\" limit:1 ","response":"range_response_count:1 size:6268"} Nov 1 10:01:21.632507 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:21.632150Z","caller":"traceutil/trace.go:171","msg":"trace[2058872415] range","detail":"{range_begin:/registry/pods/calico-system/whisker-69576fcb57-d9hxp; range_end:; response_count:1; response_revision:953; }","duration":"125.273373ms","start":"2025-11-01T10:01:21.506870Z","end":"2025-11-01T10:01:21.632144Z","steps":["trace[2058872415] 'range keys from in-memory index tree' (duration: 124.923402ms)"],"step_count":1} Nov 1 10:01:27.661963 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.661247Z","caller":"traceutil/trace.go:171","msg":"trace[602922086] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"111.744636ms","start":"2025-11-01T10:01:27.549463Z","end":"2025-11-01T10:01:27.661208Z","steps":["trace[602922086] 'process raft request' (duration: 111.711674ms)"],"step_count":1} Nov 1 10:01:27.661963 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.661269Z","caller":"traceutil/trace.go:171","msg":"trace[1835474064] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"125.825005ms","start":"2025-11-01T10:01:27.535393Z","end":"2025-11-01T10:01:27.661218Z","steps":["trace[1835474064] 'process raft request' (duration: 77.261787ms)","trace[1835474064] 'compare' (duration: 48.389993ms)"],"step_count":2} Nov 1 10:01:27.661963 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.661283Z","caller":"traceutil/trace.go:171","msg":"trace[908951762] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1119; }","duration":"121.016759ms","start":"2025-11-01T10:01:27.540246Z","end":"2025-11-01T10:01:27.661263Z","steps":["trace[908951762] 'read index received' (duration: 6.74993ms)","trace[908951762] 'applied index is now lower than readState.Index' (duration: 114.265357ms)"],"step_count":2} Nov 1 10:01:27.661963 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.661283Z","caller":"traceutil/trace.go:171","msg":"trace[55502721] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"122.838419ms","start":"2025-11-01T10:01:27.538418Z","end":"2025-11-01T10:01:27.661256Z","steps":["trace[55502721] 'process raft request' (duration: 122.725758ms)"],"step_count":1} Nov 1 10:01:27.661963 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:27.661503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.235431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:759"} Nov 1 10:01:27.662676 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.661531Z","caller":"traceutil/trace.go:171","msg":"trace[863858819] range","detail":"{range_begin:/registry/services/endpoints/calico-system/calico-typha; range_end:; response_count:1; response_revision:1094; }","duration":"121.283831ms","start":"2025-11-01T10:01:27.540240Z","end":"2025-11-01T10:01:27.661524Z","steps":["trace[863858819] 'agreement among raft nodes before linearized reading' (duration: 121.143308ms)"],"step_count":1} Nov 1 10:01:27.825562 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.824884Z","caller":"traceutil/trace.go:171","msg":"trace[1929208058] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"157.927698ms","start":"2025-11-01T10:01:27.666940Z","end":"2025-11-01T10:01:27.824867Z","steps":["trace[1929208058] 'process raft request' (duration: 149.148491ms)"],"step_count":1} Nov 1 10:01:27.826077 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825117Z","caller":"traceutil/trace.go:171","msg":"trace[557390182] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"158.101255ms","start":"2025-11-01T10:01:27.666976Z","end":"2025-11-01T10:01:27.825077Z","steps":["trace[557390182] 'process raft request' (duration: 157.859831ms)"],"step_count":1} Nov 1 10:01:27.826077 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825140Z","caller":"traceutil/trace.go:171","msg":"trace[257461094] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"151.32175ms","start":"2025-11-01T10:01:27.673799Z","end":"2025-11-01T10:01:27.825121Z","steps":["trace[257461094] 'process raft request' (duration: 151.237351ms)"],"step_count":1} Nov 1 10:01:27.826077 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825161Z","caller":"traceutil/trace.go:171","msg":"trace[1169758374] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"154.518921ms","start":"2025-11-01T10:01:27.670619Z","end":"2025-11-01T10:01:27.825137Z","steps":["trace[1169758374] 'read index received' (duration: 145.480717ms)","trace[1169758374] 'applied index is now lower than readState.Index' (duration: 9.036741ms)"],"step_count":2} Nov 1 10:01:27.826077 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:27.825354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.431273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/goldmane-666569f655-p67f4\" limit:1 ","response":"range_response_count:1 size:6595"} Nov 1 10:01:27.826077 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:27.825378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.450417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:759"} Nov 1 10:01:27.826213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825436Z","caller":"traceutil/trace.go:171","msg":"trace[426223174] range","detail":"{range_begin:/registry/services/endpoints/calico-system/calico-typha; range_end:; response_count:1; response_revision:1098; }","duration":"152.523735ms","start":"2025-11-01T10:01:27.672905Z","end":"2025-11-01T10:01:27.825428Z","steps":["trace[426223174] 'agreement among raft nodes before linearized reading' (duration: 152.379495ms)"],"step_count":1} Nov 1 10:01:27.826213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825390Z","caller":"traceutil/trace.go:171","msg":"trace[1818996657] range","detail":"{range_begin:/registry/pods/calico-system/goldmane-666569f655-p67f4; range_end:; response_count:1; response_revision:1098; }","duration":"158.481558ms","start":"2025-11-01T10:01:27.666901Z","end":"2025-11-01T10:01:27.825382Z","steps":["trace[1818996657] 'agreement among raft nodes before linearized reading' (duration: 158.345863ms)"],"step_count":1} Nov 1 10:01:27.826213 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:27.825451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.296983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-448s2.1873d9bcb2026cc0\" limit:1 ","response":"range_response_count:1 size:800"} Nov 1 10:01:27.826213 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825605Z","caller":"traceutil/trace.go:171","msg":"trace[836921286] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-448s2.1873d9bcb2026cc0; range_end:; response_count:1; response_revision:1098; }","duration":"102.463416ms","start":"2025-11-01T10:01:27.723129Z","end":"2025-11-01T10:01:27.825593Z","steps":["trace[836921286] 'agreement among raft nodes before linearized reading' (duration: 102.22081ms)"],"step_count":1} Nov 1 10:01:27.826417 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:27.825584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.889609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:759"} Nov 1 10:01:27.826417 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825642Z","caller":"traceutil/trace.go:171","msg":"trace[1877854560] range","detail":"{range_begin:/registry/services/endpoints/calico-system/calico-typha; range_end:; response_count:1; response_revision:1098; }","duration":"124.95944ms","start":"2025-11-01T10:01:27.700673Z","end":"2025-11-01T10:01:27.825632Z","steps":["trace[1877854560] 'agreement among raft nodes before linearized reading' (duration: 124.732323ms)"],"step_count":1} Nov 1 10:01:27.826417 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:27.825750Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.818845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3643"} Nov 1 10:01:27.826417 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:27.825781Z","caller":"traceutil/trace.go:171","msg":"trace[159983884] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:1098; }","duration":"124.852479ms","start":"2025-11-01T10:01:27.700923Z","end":"2025-11-01T10:01:27.825776Z","steps":["trace[159983884] 'agreement among raft nodes before linearized reading' (duration: 124.441848ms)"],"step_count":1} Nov 1 10:01:28.026804 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.026327Z","caller":"traceutil/trace.go:171","msg":"trace[821786253] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"100.77008ms","start":"2025-11-01T10:01:27.925536Z","end":"2025-11-01T10:01:28.026306Z","steps":["trace[821786253] 'read index received' (duration: 30.803947ms)","trace[821786253] 'applied index is now lower than readState.Index' (duration: 69.965151ms)"],"step_count":2} Nov 1 10:01:28.026988 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.026499Z","caller":"traceutil/trace.go:171","msg":"trace[1861851851] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"130.783463ms","start":"2025-11-01T10:01:27.895684Z","end":"2025-11-01T10:01:28.026467Z","steps":["trace[1861851851] 'process raft request' (duration: 60.649214ms)","trace[1861851851] 'compare' (duration: 69.838633ms)"],"step_count":2} Nov 1 10:01:28.026988 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:28.026544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.985494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-tiered-policy-passthrough\" limit:1 ","response":"range_response_count:1 size:680"} Nov 1 10:01:28.026988 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.026576Z","caller":"traceutil/trace.go:171","msg":"trace[1001307169] range","detail":"{range_begin:/registry/clusterrolebindings/calico-tiered-policy-passthrough; range_end:; response_count:1; response_revision:1104; }","duration":"101.040077ms","start":"2025-11-01T10:01:27.925526Z","end":"2025-11-01T10:01:28.026566Z","steps":["trace[1001307169] 'agreement among raft nodes before linearized reading' (duration: 100.865899ms)"],"step_count":1} Nov 1 10:01:28.027156 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.026839Z","caller":"traceutil/trace.go:171","msg":"trace[768602573] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"100.933618ms","start":"2025-11-01T10:01:27.925899Z","end":"2025-11-01T10:01:28.026832Z","steps":["trace[768602573] 'process raft request' (duration: 100.831456ms)"],"step_count":1} Nov 1 10:01:28.707540 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.706961Z","caller":"traceutil/trace.go:171","msg":"trace[1637054071] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"103.87749ms","start":"2025-11-01T10:01:28.603066Z","end":"2025-11-01T10:01:28.706943Z","steps":["trace[1637054071] 'read index received' (duration: 101.021712ms)","trace[1637054071] 'applied index is now lower than readState.Index' (duration: 2.855357ms)"],"step_count":2} Nov 1 10:01:28.707540 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.706985Z","caller":"traceutil/trace.go:171","msg":"trace[282132942] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"104.417975ms","start":"2025-11-01T10:01:28.602548Z","end":"2025-11-01T10:01:28.706966Z","steps":["trace[282132942] 'process raft request' (duration: 101.575743ms)"],"step_count":1} Nov 1 10:01:28.707540 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:28.707174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.933664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:4810"} Nov 1 10:01:28.707540 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.707209Z","caller":"traceutil/trace.go:171","msg":"trace[1765034109] range","detail":"{range_begin:/registry/deployments/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:1140; }","duration":"103.988537ms","start":"2025-11-01T10:01:28.603214Z","end":"2025-11-01T10:01:28.707202Z","steps":["trace[1765034109] 'agreement among raft nodes before linearized reading' (duration: 103.824008ms)"],"step_count":1} Nov 1 10:01:28.707540 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:28.707197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.179638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/goldmane-ca-bundle\" limit:1 ","response":"range_response_count:1 size:1959"} Nov 1 10:01:28.708190 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.707306Z","caller":"traceutil/trace.go:171","msg":"trace[938500608] range","detail":"{range_begin:/registry/configmaps/calico-system/goldmane-ca-bundle; range_end:; response_count:1; response_revision:1140; }","duration":"102.289244ms","start":"2025-11-01T10:01:28.605007Z","end":"2025-11-01T10:01:28.707296Z","steps":["trace[938500608] 'agreement among raft nodes before linearized reading' (duration: 102.128692ms)"],"step_count":1} Nov 1 10:01:28.708190 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:28.707294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.152196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/ippools\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:01:28.708190 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:28.707458Z","caller":"traceutil/trace.go:171","msg":"trace[1880538984] range","detail":"{range_begin:/registry/crd.projectcalico.org/ippools; range_end:; response_count:0; response_revision:1140; }","duration":"104.369985ms","start":"2025-11-01T10:01:28.603056Z","end":"2025-11-01T10:01:28.707426Z","steps":["trace[1880538984] 'agreement among raft nodes before linearized reading' (duration: 104.092192ms)"],"step_count":1} Nov 1 10:01:29.721007 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.720471Z","caller":"traceutil/trace.go:171","msg":"trace[1781975035] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"147.699704ms","start":"2025-11-01T10:01:29.572750Z","end":"2025-11-01T10:01:29.720450Z","steps":["trace[1781975035] 'read index received' (duration: 62.63494ms)","trace[1781975035] 'applied index is now lower than readState.Index' (duration: 85.064113ms)"],"step_count":2} Nov 1 10:01:29.721007 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.720527Z","caller":"traceutil/trace.go:171","msg":"trace[739786743] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"164.014759ms","start":"2025-11-01T10:01:29.556481Z","end":"2025-11-01T10:01:29.720496Z","steps":["trace[739786743] 'process raft request' (duration: 78.954234ms)","trace[739786743] 'compare' (duration: 84.91411ms)"],"step_count":2} Nov 1 10:01:29.721007 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:29.720659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.887066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/calico-apiserver/calico-apiserver-65f4874cbd-sm9hg.1873d9bdd272b6a0\" limit:1 ","response":"range_response_count:1 size:831"} Nov 1 10:01:29.721007 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.720692Z","caller":"traceutil/trace.go:171","msg":"trace[1982691354] range","detail":"{range_begin:/registry/events/calico-apiserver/calico-apiserver-65f4874cbd-sm9hg.1873d9bdd272b6a0; range_end:; response_count:1; response_revision:1157; }","duration":"147.93698ms","start":"2025-11-01T10:01:29.572747Z","end":"2025-11-01T10:01:29.720684Z","steps":["trace[1982691354] 'agreement among raft nodes before linearized reading' (duration: 147.806764ms)"],"step_count":1} Nov 1 10:01:29.754565 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:29.754167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.320393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-apiserver:csr-creator\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:01:29.754565 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.754267Z","caller":"traceutil/trace.go:171","msg":"trace[1321227509] range","detail":"{range_begin:/registry/clusterrolebindings/calico-apiserver:csr-creator; range_end:; response_count:0; response_revision:1158; }","duration":"163.440177ms","start":"2025-11-01T10:01:29.590810Z","end":"2025-11-01T10:01:29.754250Z","steps":["trace[1321227509] 'agreement among raft nodes before linearized reading' (duration: 163.275026ms)"],"step_count":1} Nov 1 10:01:29.921882 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.921312Z","caller":"traceutil/trace.go:171","msg":"trace[1820824149] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"155.664784ms","start":"2025-11-01T10:01:29.765628Z","end":"2025-11-01T10:01:29.921292Z","steps":["trace[1820824149] 'process raft request' (duration: 150.219826ms)"],"step_count":1} Nov 1 10:01:29.921882 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.921503Z","caller":"traceutil/trace.go:171","msg":"trace[784547376] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1190; }","duration":"128.278452ms","start":"2025-11-01T10:01:29.793199Z","end":"2025-11-01T10:01:29.921477Z","steps":["trace[784547376] 'read index received' (duration: 122.609352ms)","trace[784547376] 'applied index is now lower than readState.Index' (duration: 5.668318ms)"],"step_count":2} Nov 1 10:01:29.921882 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.921589Z","caller":"traceutil/trace.go:171","msg":"trace[1050411539] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"153.025116ms","start":"2025-11-01T10:01:29.768554Z","end":"2025-11-01T10:01:29.921579Z","steps":["trace[1050411539] 'process raft request' (duration: 152.70351ms)"],"step_count":1} Nov 1 10:01:29.922207 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:29.921687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.107384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/calico-apiserver/calico-apiserver-65f4874cbd-sm9hg.1873d9bdd272f516\" limit:1 ","response":"range_response_count:1 size:789"} Nov 1 10:01:29.922207 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.921715Z","caller":"traceutil/trace.go:171","msg":"trace[904039216] range","detail":"{range_begin:/registry/events/calico-apiserver/calico-apiserver-65f4874cbd-sm9hg.1873d9bdd272f516; range_end:; response_count:1; response_revision:1162; }","duration":"160.152689ms","start":"2025-11-01T10:01:29.761556Z","end":"2025-11-01T10:01:29.921709Z","steps":["trace[904039216] 'agreement among raft nodes before linearized reading' (duration: 160.020702ms)"],"step_count":1} Nov 1 10:01:29.930463 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:29.929978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.033688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-crds\" limit:1 ","response":"range_response_count:1 size:1255"} Nov 1 10:01:29.930463 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.929997Z","caller":"traceutil/trace.go:171","msg":"trace[715065235] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"133.915101ms","start":"2025-11-01T10:01:29.796071Z","end":"2025-11-01T10:01:29.929986Z","steps":["trace[715065235] 'process raft request' (duration: 133.686139ms)"],"step_count":1} Nov 1 10:01:29.930463 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:29.930025Z","caller":"traceutil/trace.go:171","msg":"trace[979142762] range","detail":"{range_begin:/registry/clusterroles/calico-crds; range_end:; response_count:1; response_revision:1163; }","duration":"132.092961ms","start":"2025-11-01T10:01:29.797925Z","end":"2025-11-01T10:01:29.930018Z","steps":["trace[979142762] 'agreement among raft nodes before linearized reading' (duration: 131.944942ms)"],"step_count":1} Nov 1 10:01:30.193722 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:30.193338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.109872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 1 10:01:30.193722 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.193476Z","caller":"traceutil/trace.go:171","msg":"trace[925430921] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"239.141829ms","start":"2025-11-01T10:01:29.954322Z","end":"2025-11-01T10:01:30.193464Z","steps":["trace[925430921] 'process raft request' (duration: 239.092145ms)"],"step_count":1} Nov 1 10:01:30.193722 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.193503Z","caller":"traceutil/trace.go:171","msg":"trace[106885748] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"239.459597ms","start":"2025-11-01T10:01:29.954024Z","end":"2025-11-01T10:01:30.193484Z","steps":["trace[106885748] 'process raft request' (duration: 105.125802ms)","trace[106885748] 'compare' (duration: 134.011898ms)"],"step_count":2} Nov 1 10:01:30.197352 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.197020Z","caller":"traceutil/trace.go:171","msg":"trace[1824188148] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1198; }","duration":"224.087706ms","start":"2025-11-01T10:01:29.972924Z","end":"2025-11-01T10:01:30.197012Z","steps":["trace[1824188148] 'read index received' (duration: 86.28264ms)","trace[1824188148] 'applied index is now lower than readState.Index' (duration: 137.804715ms)"],"step_count":2} Nov 1 10:01:30.197352 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.197073Z","caller":"traceutil/trace.go:171","msg":"trace[97353081] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"225.548454ms","start":"2025-11-01T10:01:29.971518Z","end":"2025-11-01T10:01:30.197067Z","steps":["trace[97353081] 'process raft request' (duration: 225.415594ms)"],"step_count":1} Nov 1 10:01:30.197664 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:30.197194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.26541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-tier-getter\" limit:1 ","response":"range_response_count:1 size:647"} Nov 1 10:01:30.197664 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.197224Z","caller":"traceutil/trace.go:171","msg":"trace[848452950] range","detail":"{range_begin:/registry/clusterrolebindings/calico-tier-getter; range_end:; response_count:1; response_revision:1172; }","duration":"224.29697ms","start":"2025-11-01T10:01:29.972921Z","end":"2025-11-01T10:01:30.197217Z","steps":["trace[848452950] 'agreement among raft nodes before linearized reading' (duration: 224.194487ms)"],"step_count":1} Nov 1 10:01:30.197664 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.197144Z","caller":"traceutil/trace.go:171","msg":"trace[376971040] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"157.514748ms","start":"2025-11-01T10:01:30.039604Z","end":"2025-11-01T10:01:30.197119Z","steps":["trace[376971040] 'process raft request' (duration: 157.373332ms)"],"step_count":1} Nov 1 10:01:30.214043 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.213707Z","caller":"traceutil/trace.go:171","msg":"trace[181654876] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"152.627947ms","start":"2025-11-01T10:01:30.061066Z","end":"2025-11-01T10:01:30.213694Z","steps":["trace[181654876] 'process raft request' (duration: 152.567684ms)"],"step_count":1} Nov 1 10:01:30.214043 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:30.213764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.979809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:759"} Nov 1 10:01:30.214043 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:30.213776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.875136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/bgpconfigurations/default\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:01:30.214043 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.213792Z","caller":"traceutil/trace.go:171","msg":"trace[1711886897] range","detail":"{range_begin:/registry/services/endpoints/calico-system/calico-typha; range_end:; response_count:1; response_revision:1173; }","duration":"175.009665ms","start":"2025-11-01T10:01:30.038775Z","end":"2025-11-01T10:01:30.213785Z","steps":["trace[1711886897] 'agreement among raft nodes before linearized reading' (duration: 174.929845ms)"],"step_count":1} Nov 1 10:01:30.214043 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:30.213807Z","caller":"traceutil/trace.go:171","msg":"trace[304727152] range","detail":"{range_begin:/registry/crd.projectcalico.org/bgpconfigurations/default; range_end:; response_count:0; response_revision:1173; }","duration":"166.91948ms","start":"2025-11-01T10:01:30.046881Z","end":"2025-11-01T10:01:30.213800Z","steps":["trace[304727152] 'agreement among raft nodes before linearized reading' (duration: 166.823619ms)"],"step_count":1} Nov 1 10:01:32.840906 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:32.840468Z","caller":"traceutil/trace.go:171","msg":"trace[1531694199] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"106.565672ms","start":"2025-11-01T10:01:32.733880Z","end":"2025-11-01T10:01:32.840446Z","steps":["trace[1531694199] 'read index received' (duration: 106.449813ms)","trace[1531694199] 'applied index is now lower than readState.Index' (duration: 115.508µs)"],"step_count":2} Nov 1 10:01:32.840906 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:32.840510Z","caller":"traceutil/trace.go:171","msg":"trace[519495689] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"129.944396ms","start":"2025-11-01T10:01:32.710530Z","end":"2025-11-01T10:01:32.840474Z","steps":["trace[519495689] 'process raft request' (duration: 129.794402ms)"],"step_count":1} Nov 1 10:01:32.840906 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:32.840609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.717529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:01:32.840906 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:32.840634Z","caller":"traceutil/trace.go:171","msg":"trace[1592258553] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:1194; }","duration":"106.759238ms","start":"2025-11-01T10:01:32.733868Z","end":"2025-11-01T10:01:32.840627Z","steps":["trace[1592258553] 'agreement among raft nodes before linearized reading' (duration: 106.677283ms)"],"step_count":1} Nov 1 10:01:32.840906 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:32.840673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.245342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/tigera-apiserver-access-tigera-crds\" limit:1 ","response":"range_response_count:0 size:5"} Nov 1 10:01:32.841729 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:32.840748Z","caller":"traceutil/trace.go:171","msg":"trace[887266500] range","detail":"{range_begin:/registry/clusterrolebindings/tigera-apiserver-access-tigera-crds; range_end:; response_count:0; response_revision:1194; }","duration":"100.333579ms","start":"2025-11-01T10:01:32.740396Z","end":"2025-11-01T10:01:32.840729Z","steps":["trace[887266500] 'agreement among raft nodes before linearized reading' (duration: 100.209895ms)"],"step_count":1} Nov 1 10:01:34.878192 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:34.877755Z","caller":"traceutil/trace.go:171","msg":"trace[446948541] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"121.970461ms","start":"2025-11-01T10:01:34.755748Z","end":"2025-11-01T10:01:34.877718Z","steps":["trace[446948541] 'process raft request' (duration: 57.194052ms)","trace[446948541] 'compare' (duration: 64.548728ms)"],"step_count":2} Nov 1 10:01:38.063325 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:38.062650Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.59202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 1 10:01:38.063325 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:38.062823Z","caller":"traceutil/trace.go:171","msg":"trace[1851548647] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"230.386208ms","start":"2025-11-01T10:01:37.832415Z","end":"2025-11-01T10:01:38.062801Z","steps":["trace[1851548647] 'process raft request' (duration: 230.308981ms)"],"step_count":1} Nov 1 10:01:38.063325 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:38.062912Z","caller":"traceutil/trace.go:171","msg":"trace[1319049082] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"301.936995ms","start":"2025-11-01T10:01:37.760968Z","end":"2025-11-01T10:01:38.062905Z","steps":["trace[1319049082] 'process raft request' (duration: 178.013135ms)","trace[1319049082] 'compare' (duration: 123.486068ms)"],"step_count":2} Nov 1 10:01:38.063325 etcd-wrapper[1906]: {"level":"info","ts":"2025-11-01T10:01:38.062913Z","caller":"traceutil/trace.go:171","msg":"trace[866055071] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"205.80661ms","start":"2025-11-01T10:01:37.857095Z","end":"2025-11-01T10:01:38.062902Z","steps":["trace[866055071] 'process raft request' (duration: 205.660182ms)"],"step_count":1} Nov 1 10:01:38.063952 etcd-wrapper[1906]: {"level":"warn","ts":"2025-11-01T10:01:38.063057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-01T10:01:37.760953Z","time spent":"301.975269ms","remote":"10.0.0.25:56978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14864,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}