Nov 5 04:44:52.373812 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 Wed Nov 5 03:01:50 -00 2025 Nov 5 04:44:52.373847 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=9a076e14dca937d9663502c090e1ff4931f585a3752c3aa4c87feb67d6e5a465 Nov 5 04:44:52.373857 kernel: BIOS-provided physical RAM map: Nov 5 04:44:52.373867 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 5 04:44:52.373874 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Nov 5 04:44:52.373881 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Nov 5 04:44:52.373890 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Nov 5 04:44:52.373897 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Nov 5 04:44:52.373904 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable Nov 5 04:44:52.373911 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Nov 5 04:44:52.373918 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable Nov 5 04:44:52.373928 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Nov 5 04:44:52.373934 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable Nov 5 04:44:52.373941 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Nov 5 04:44:52.373950 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Nov 5 04:44:52.373958 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Nov 5 04:44:52.373968 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce90fff] usable Nov 5 04:44:52.373975 kernel: BIOS-e820: [mem 0x000000009ce91000-0x000000009ce94fff] reserved Nov 5 04:44:52.373982 kernel: BIOS-e820: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS Nov 5 04:44:52.373990 kernel: BIOS-e820: [mem 0x000000009ce97000-0x000000009cedbfff] usable Nov 5 04:44:52.373997 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Nov 5 04:44:52.374004 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Nov 5 04:44:52.374012 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Nov 5 04:44:52.374019 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 5 04:44:52.374026 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Nov 5 04:44:52.374034 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 5 04:44:52.374043 kernel: NX (Execute Disable) protection: active Nov 5 04:44:52.374050 kernel: APIC: Static calls initialized Nov 5 04:44:52.374058 kernel: e820: update [mem 0x9b320018-0x9b329c57] usable ==> usable Nov 5 04:44:52.374066 kernel: e820: update [mem 0x9b2e3018-0x9b31fe57] usable ==> usable Nov 5 04:44:52.374073 kernel: extended physical RAM map: Nov 5 04:44:52.374080 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 5 04:44:52.374088 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable Nov 5 04:44:52.374095 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Nov 5 04:44:52.374103 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable Nov 5 04:44:52.374110 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Nov 5 04:44:52.374117 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable Nov 5 04:44:52.374127 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Nov 5 04:44:52.374134 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b2e3017] usable Nov 5 04:44:52.374142 kernel: reserve setup_data: [mem 0x000000009b2e3018-0x000000009b31fe57] usable Nov 5 04:44:52.374153 kernel: reserve setup_data: [mem 0x000000009b31fe58-0x000000009b320017] usable Nov 5 04:44:52.374163 kernel: reserve setup_data: [mem 0x000000009b320018-0x000000009b329c57] usable Nov 5 04:44:52.374171 kernel: reserve setup_data: [mem 0x000000009b329c58-0x000000009bd3efff] usable Nov 5 04:44:52.374178 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Nov 5 04:44:52.374186 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable Nov 5 04:44:52.374194 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Nov 5 04:44:52.374202 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Nov 5 04:44:52.374209 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Nov 5 04:44:52.374217 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce90fff] usable Nov 5 04:44:52.374225 kernel: reserve setup_data: [mem 0x000000009ce91000-0x000000009ce94fff] reserved Nov 5 04:44:52.374235 kernel: reserve setup_data: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS Nov 5 04:44:52.374242 kernel: reserve setup_data: [mem 0x000000009ce97000-0x000000009cedbfff] usable Nov 5 04:44:52.374250 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Nov 5 04:44:52.374258 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Nov 5 04:44:52.374265 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Nov 5 04:44:52.374273 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 5 04:44:52.374281 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Nov 5 04:44:52.374288 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 5 04:44:52.374296 kernel: efi: EFI v2.7 by EDK II Nov 5 04:44:52.374312 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b9e4198 RNG=0x9cb73018 Nov 5 04:44:52.374333 kernel: random: crng init done Nov 5 04:44:52.374344 kernel: efi: Remove mem151: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Nov 5 04:44:52.374351 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Nov 5 04:44:52.374359 kernel: secureboot: Secure boot disabled Nov 5 04:44:52.374367 kernel: SMBIOS 2.8 present. Nov 5 04:44:52.374375 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Nov 5 04:44:52.374382 kernel: DMI: Memory slots populated: 1/1 Nov 5 04:44:52.374390 kernel: Hypervisor detected: KVM Nov 5 04:44:52.374397 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Nov 5 04:44:52.374405 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 5 04:44:52.374413 kernel: kvm-clock: using sched offset of 5372893165 cycles Nov 5 04:44:52.374421 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 5 04:44:52.374432 kernel: tsc: Detected 2794.750 MHz processor Nov 5 04:44:52.374441 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 5 04:44:52.374450 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 5 04:44:52.374458 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Nov 5 04:44:52.374466 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Nov 5 04:44:52.374474 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 5 04:44:52.374483 kernel: Using GB pages for direct mapping Nov 5 04:44:52.374493 kernel: ACPI: Early table checksum verification disabled Nov 5 04:44:52.374501 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Nov 5 04:44:52.374510 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Nov 5 04:44:52.374518 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 04:44:52.374527 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 04:44:52.374535 kernel: ACPI: FACS 0x000000009CBDD000 000040 Nov 5 04:44:52.374543 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 04:44:52.374554 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 04:44:52.374562 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 04:44:52.374570 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 04:44:52.374578 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Nov 5 04:44:52.374586 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Nov 5 04:44:52.374595 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Nov 5 04:44:52.374603 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Nov 5 04:44:52.374613 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Nov 5 04:44:52.374622 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Nov 5 04:44:52.374630 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Nov 5 04:44:52.374638 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Nov 5 04:44:52.374646 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Nov 5 04:44:52.374654 kernel: No NUMA configuration found Nov 5 04:44:52.374662 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] Nov 5 04:44:52.374670 kernel: NODE_DATA(0) allocated [mem 0x9ce36dc0-0x9ce3dfff] Nov 5 04:44:52.374681 kernel: Zone ranges: Nov 5 04:44:52.374689 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 5 04:44:52.374697 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] Nov 5 04:44:52.374705 kernel: Normal empty Nov 5 04:44:52.374713 kernel: Device empty Nov 5 04:44:52.374721 kernel: Movable zone start for each node Nov 5 04:44:52.374729 kernel: Early memory node ranges Nov 5 04:44:52.374740 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 5 04:44:52.374747 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Nov 5 04:44:52.374756 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Nov 5 04:44:52.374774 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] Nov 5 04:44:52.374782 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] Nov 5 04:44:52.374790 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] Nov 5 04:44:52.374799 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce90fff] Nov 5 04:44:52.374807 kernel: node 0: [mem 0x000000009ce97000-0x000000009cedbfff] Nov 5 04:44:52.374817 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] Nov 5 04:44:52.374826 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 5 04:44:52.374841 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 5 04:44:52.374851 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Nov 5 04:44:52.374860 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 5 04:44:52.374868 kernel: On node 0, zone DMA: 239 pages in unavailable ranges Nov 5 04:44:52.374877 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Nov 5 04:44:52.374886 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Nov 5 04:44:52.374894 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Nov 5 04:44:52.374903 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges Nov 5 04:44:52.374914 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 5 04:44:52.374922 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 5 04:44:52.374931 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 5 04:44:52.375609 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 5 04:44:52.375655 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 5 04:44:52.375666 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 5 04:44:52.375675 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 5 04:44:52.375684 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 5 04:44:52.375693 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 5 04:44:52.375702 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 5 04:44:52.375711 kernel: TSC deadline timer available Nov 5 04:44:52.375722 kernel: CPU topo: Max. logical packages: 1 Nov 5 04:44:52.375731 kernel: CPU topo: Max. logical dies: 1 Nov 5 04:44:52.375740 kernel: CPU topo: Max. dies per package: 1 Nov 5 04:44:52.375748 kernel: CPU topo: Max. threads per core: 1 Nov 5 04:44:52.375757 kernel: CPU topo: Num. cores per package: 4 Nov 5 04:44:52.375766 kernel: CPU topo: Num. threads per package: 4 Nov 5 04:44:52.375774 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Nov 5 04:44:52.375785 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 5 04:44:52.375794 kernel: kvm-guest: KVM setup pv remote TLB flush Nov 5 04:44:52.375803 kernel: kvm-guest: setup PV sched yield Nov 5 04:44:52.375812 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Nov 5 04:44:52.375821 kernel: Booting paravirtualized kernel on KVM Nov 5 04:44:52.375831 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 5 04:44:52.375840 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Nov 5 04:44:52.375851 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Nov 5 04:44:52.375860 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Nov 5 04:44:52.375869 kernel: pcpu-alloc: [0] 0 1 2 3 Nov 5 04:44:52.375878 kernel: kvm-guest: PV spinlocks enabled Nov 5 04:44:52.375887 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 5 04:44:52.375898 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=9a076e14dca937d9663502c090e1ff4931f585a3752c3aa4c87feb67d6e5a465 Nov 5 04:44:52.375908 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 04:44:52.375919 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 04:44:52.375928 kernel: Fallback order for Node 0: 0 Nov 5 04:44:52.375937 kernel: Built 1 zonelists, mobility grouping on. Total pages: 641450 Nov 5 04:44:52.375946 kernel: Policy zone: DMA32 Nov 5 04:44:52.375955 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 04:44:52.375963 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 5 04:44:52.375972 kernel: ftrace: allocating 40092 entries in 157 pages Nov 5 04:44:52.375983 kernel: ftrace: allocated 157 pages with 5 groups Nov 5 04:44:52.375992 kernel: Dynamic Preempt: voluntary Nov 5 04:44:52.376001 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 04:44:52.376010 kernel: rcu: RCU event tracing is enabled. Nov 5 04:44:52.376019 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 5 04:44:52.376028 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 04:44:52.376037 kernel: Rude variant of Tasks RCU enabled. Nov 5 04:44:52.376045 kernel: Tracing variant of Tasks RCU enabled. Nov 5 04:44:52.376056 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 04:44:52.376065 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 5 04:44:52.376073 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 04:44:52.376083 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 04:44:52.376091 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 04:44:52.376100 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Nov 5 04:44:52.376109 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 5 04:44:52.376120 kernel: Console: colour dummy device 80x25 Nov 5 04:44:52.376129 kernel: printk: legacy console [ttyS0] enabled Nov 5 04:44:52.376137 kernel: ACPI: Core revision 20240827 Nov 5 04:44:52.376146 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 5 04:44:52.376155 kernel: APIC: Switch to symmetric I/O mode setup Nov 5 04:44:52.376164 kernel: x2apic enabled Nov 5 04:44:52.376173 kernel: APIC: Switched APIC routing to: physical x2apic Nov 5 04:44:52.376184 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Nov 5 04:44:52.376193 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Nov 5 04:44:52.376202 kernel: kvm-guest: setup PV IPIs Nov 5 04:44:52.376213 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 5 04:44:52.376223 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Nov 5 04:44:52.376233 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Nov 5 04:44:52.376242 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Nov 5 04:44:52.376253 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Nov 5 04:44:52.376261 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Nov 5 04:44:52.376270 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 5 04:44:52.376279 kernel: Spectre V2 : Mitigation: Retpolines Nov 5 04:44:52.376288 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 5 04:44:52.376303 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Nov 5 04:44:52.376312 kernel: active return thunk: retbleed_return_thunk Nov 5 04:44:52.376338 kernel: RETBleed: Mitigation: untrained return thunk Nov 5 04:44:52.376347 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Nov 5 04:44:52.376356 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Nov 5 04:44:52.376365 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Nov 5 04:44:52.376374 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Nov 5 04:44:52.376383 kernel: active return thunk: srso_return_thunk Nov 5 04:44:52.376392 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Nov 5 04:44:52.376403 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 5 04:44:52.376412 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 5 04:44:52.376421 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 5 04:44:52.376429 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 5 04:44:52.376438 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Nov 5 04:44:52.376447 kernel: Freeing SMP alternatives memory: 32K Nov 5 04:44:52.376455 kernel: pid_max: default: 32768 minimum: 301 Nov 5 04:44:52.376466 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 5 04:44:52.376475 kernel: landlock: Up and running. Nov 5 04:44:52.376483 kernel: SELinux: Initializing. Nov 5 04:44:52.376492 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 04:44:52.376501 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 04:44:52.376510 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Nov 5 04:44:52.376519 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Nov 5 04:44:52.376529 kernel: ... version: 0 Nov 5 04:44:52.376538 kernel: ... bit width: 48 Nov 5 04:44:52.376546 kernel: ... generic registers: 6 Nov 5 04:44:52.376555 kernel: ... value mask: 0000ffffffffffff Nov 5 04:44:52.376564 kernel: ... max period: 00007fffffffffff Nov 5 04:44:52.376573 kernel: ... fixed-purpose events: 0 Nov 5 04:44:52.376581 kernel: ... event mask: 000000000000003f Nov 5 04:44:52.376592 kernel: signal: max sigframe size: 1776 Nov 5 04:44:52.376601 kernel: rcu: Hierarchical SRCU implementation. Nov 5 04:44:52.376610 kernel: rcu: Max phase no-delay instances is 400. Nov 5 04:44:52.376618 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 5 04:44:52.376627 kernel: smp: Bringing up secondary CPUs ... Nov 5 04:44:52.376636 kernel: smpboot: x86: Booting SMP configuration: Nov 5 04:44:52.376645 kernel: .... node #0, CPUs: #1 #2 #3 Nov 5 04:44:52.376653 kernel: smp: Brought up 1 node, 4 CPUs Nov 5 04:44:52.376664 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Nov 5 04:44:52.376673 kernel: Memory: 2441100K/2565800K available (14336K kernel code, 2443K rwdata, 29892K rodata, 15348K init, 2696K bss, 118764K reserved, 0K cma-reserved) Nov 5 04:44:52.376682 kernel: devtmpfs: initialized Nov 5 04:44:52.376691 kernel: x86/mm: Memory block size: 128MB Nov 5 04:44:52.376699 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Nov 5 04:44:52.376708 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Nov 5 04:44:52.376717 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) Nov 5 04:44:52.376728 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Nov 5 04:44:52.376736 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce95000-0x9ce96fff] (8192 bytes) Nov 5 04:44:52.376745 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Nov 5 04:44:52.376754 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 04:44:52.376763 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 5 04:44:52.376772 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 04:44:52.376782 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 5 04:44:52.376791 kernel: audit: initializing netlink subsys (disabled) Nov 5 04:44:52.376800 kernel: audit: type=2000 audit(1762317888.772:1): state=initialized audit_enabled=0 res=1 Nov 5 04:44:52.376809 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 04:44:52.376817 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 5 04:44:52.376825 kernel: cpuidle: using governor menu Nov 5 04:44:52.376834 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 04:44:52.376843 kernel: dca service started, version 1.12.1 Nov 5 04:44:52.376854 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Nov 5 04:44:52.376863 kernel: PCI: Using configuration type 1 for base access Nov 5 04:44:52.376871 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 5 04:44:52.376881 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 04:44:52.376889 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 5 04:44:52.376898 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 04:44:52.376907 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 5 04:44:52.376917 kernel: ACPI: Added _OSI(Module Device) Nov 5 04:44:52.376926 kernel: ACPI: Added _OSI(Processor Device) Nov 5 04:44:52.376935 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 04:44:52.376943 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 04:44:52.376958 kernel: ACPI: Interpreter enabled Nov 5 04:44:52.376967 kernel: ACPI: PM: (supports S0 S3 S5) Nov 5 04:44:52.376975 kernel: ACPI: Using IOAPIC for interrupt routing Nov 5 04:44:52.376988 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 5 04:44:52.376997 kernel: PCI: Using E820 reservations for host bridge windows Nov 5 04:44:52.377005 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Nov 5 04:44:52.377014 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 5 04:44:52.377295 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 5 04:44:52.377506 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Nov 5 04:44:52.377688 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Nov 5 04:44:52.377700 kernel: PCI host bridge to bus 0000:00 Nov 5 04:44:52.377873 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 5 04:44:52.378101 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 5 04:44:52.378483 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 5 04:44:52.382487 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Nov 5 04:44:52.382753 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Nov 5 04:44:52.382936 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Nov 5 04:44:52.383102 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 5 04:44:52.383368 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Nov 5 04:44:52.383593 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Nov 5 04:44:52.383776 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Nov 5 04:44:52.384026 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Nov 5 04:44:52.384475 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Nov 5 04:44:52.384657 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 5 04:44:52.384887 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 5 04:44:52.385121 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Nov 5 04:44:52.385402 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Nov 5 04:44:52.385596 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref] Nov 5 04:44:52.385780 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Nov 5 04:44:52.385972 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Nov 5 04:44:52.386220 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Nov 5 04:44:52.386444 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref] Nov 5 04:44:52.386708 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Nov 5 04:44:52.386887 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Nov 5 04:44:52.387093 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Nov 5 04:44:52.387379 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref] Nov 5 04:44:52.387585 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Nov 5 04:44:52.387797 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Nov 5 04:44:52.388012 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Nov 5 04:44:52.388309 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Nov 5 04:44:52.388848 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Nov 5 04:44:52.389197 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Nov 5 04:44:52.389422 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Nov 5 04:44:52.389631 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Nov 5 04:44:52.389644 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 5 04:44:52.389653 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 5 04:44:52.389662 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 5 04:44:52.389671 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 5 04:44:52.389679 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Nov 5 04:44:52.389692 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Nov 5 04:44:52.389701 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Nov 5 04:44:52.389709 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Nov 5 04:44:52.389718 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Nov 5 04:44:52.389727 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Nov 5 04:44:52.389736 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Nov 5 04:44:52.389744 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Nov 5 04:44:52.389755 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Nov 5 04:44:52.389764 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Nov 5 04:44:52.389772 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Nov 5 04:44:52.389781 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Nov 5 04:44:52.389789 kernel: iommu: Default domain type: Translated Nov 5 04:44:52.389798 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 5 04:44:52.389807 kernel: efivars: Registered efivars operations Nov 5 04:44:52.389818 kernel: PCI: Using ACPI for IRQ routing Nov 5 04:44:52.389826 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 5 04:44:52.389835 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Nov 5 04:44:52.389852 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] Nov 5 04:44:52.389869 kernel: e820: reserve RAM buffer [mem 0x9b2e3018-0x9bffffff] Nov 5 04:44:52.389880 kernel: e820: reserve RAM buffer [mem 0x9b320018-0x9bffffff] Nov 5 04:44:52.389888 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] Nov 5 04:44:52.389897 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] Nov 5 04:44:52.389908 kernel: e820: reserve RAM buffer [mem 0x9ce91000-0x9fffffff] Nov 5 04:44:52.389917 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] Nov 5 04:44:52.390230 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Nov 5 04:44:52.390470 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Nov 5 04:44:52.390643 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 5 04:44:52.390655 kernel: vgaarb: loaded Nov 5 04:44:52.390686 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 5 04:44:52.390696 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 5 04:44:52.390704 kernel: clocksource: Switched to clocksource kvm-clock Nov 5 04:44:52.390713 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 04:44:52.390722 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 04:44:52.390731 kernel: pnp: PnP ACPI init Nov 5 04:44:52.391007 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Nov 5 04:44:52.391027 kernel: pnp: PnP ACPI: found 6 devices Nov 5 04:44:52.391049 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 5 04:44:52.391061 kernel: NET: Registered PF_INET protocol family Nov 5 04:44:52.391070 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 5 04:44:52.391079 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 5 04:44:52.391088 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 5 04:44:52.391101 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 5 04:44:52.391110 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 5 04:44:52.391136 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 5 04:44:52.391161 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 04:44:52.391175 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 04:44:52.391184 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 5 04:44:52.391193 kernel: NET: Registered PF_XDP protocol family Nov 5 04:44:52.391517 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Nov 5 04:44:52.391699 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Nov 5 04:44:52.391875 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 5 04:44:52.392123 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 5 04:44:52.392345 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 5 04:44:52.392507 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Nov 5 04:44:52.392796 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Nov 5 04:44:52.392979 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Nov 5 04:44:52.392992 kernel: PCI: CLS 0 bytes, default 64 Nov 5 04:44:52.393002 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Nov 5 04:44:52.393015 kernel: Initialise system trusted keyrings Nov 5 04:44:52.393026 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 5 04:44:52.393035 kernel: Key type asymmetric registered Nov 5 04:44:52.393044 kernel: Asymmetric key parser 'x509' registered Nov 5 04:44:52.393052 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 5 04:44:52.393061 kernel: io scheduler mq-deadline registered Nov 5 04:44:52.393070 kernel: io scheduler kyber registered Nov 5 04:44:52.393080 kernel: io scheduler bfq registered Nov 5 04:44:52.393091 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 5 04:44:52.393101 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Nov 5 04:44:52.393110 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Nov 5 04:44:52.393119 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Nov 5 04:44:52.393128 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 04:44:52.393137 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 5 04:44:52.393146 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 5 04:44:52.393158 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 5 04:44:52.393166 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 5 04:44:52.393395 kernel: rtc_cmos 00:04: RTC can wake from S4 Nov 5 04:44:52.393411 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Nov 5 04:44:52.393678 kernel: rtc_cmos 00:04: registered as rtc0 Nov 5 04:44:52.393849 kernel: rtc_cmos 00:04: setting system clock to 2025-11-05T04:44:50 UTC (1762317890) Nov 5 04:44:52.394020 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Nov 5 04:44:52.394032 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Nov 5 04:44:52.394041 kernel: efifb: probing for efifb Nov 5 04:44:52.394050 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Nov 5 04:44:52.394059 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Nov 5 04:44:52.394068 kernel: efifb: scrolling: redraw Nov 5 04:44:52.394077 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 5 04:44:52.394089 kernel: Console: switching to colour frame buffer device 160x50 Nov 5 04:44:52.394099 kernel: fb0: EFI VGA frame buffer device Nov 5 04:44:52.394107 kernel: pstore: Using crash dump compression: deflate Nov 5 04:44:52.394116 kernel: pstore: Registered efi_pstore as persistent store backend Nov 5 04:44:52.394125 kernel: NET: Registered PF_INET6 protocol family Nov 5 04:44:52.394134 kernel: Segment Routing with IPv6 Nov 5 04:44:52.394143 kernel: In-situ OAM (IOAM) with IPv6 Nov 5 04:44:52.394152 kernel: NET: Registered PF_PACKET protocol family Nov 5 04:44:52.394163 kernel: Key type dns_resolver registered Nov 5 04:44:52.394172 kernel: IPI shorthand broadcast: enabled Nov 5 04:44:52.394181 kernel: sched_clock: Marking stable (2091003416, 302459511)->(2540294852, -146831925) Nov 5 04:44:52.394190 kernel: registered taskstats version 1 Nov 5 04:44:52.394199 kernel: Loading compiled-in X.509 certificates Nov 5 04:44:52.394208 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: cfd469c5acf75e2b7be33dd554bbf88cbfe73c93' Nov 5 04:44:52.394217 kernel: Demotion targets for Node 0: null Nov 5 04:44:52.394228 kernel: Key type .fscrypt registered Nov 5 04:44:52.394237 kernel: Key type fscrypt-provisioning registered Nov 5 04:44:52.394248 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 04:44:52.394274 kernel: ima: Allocated hash algorithm: sha1 Nov 5 04:44:52.394294 kernel: ima: No architecture policies found Nov 5 04:44:52.394311 kernel: clk: Disabling unused clocks Nov 5 04:44:52.394335 kernel: Freeing unused kernel image (initmem) memory: 15348K Nov 5 04:44:52.394348 kernel: Write protecting the kernel read-only data: 45056k Nov 5 04:44:52.394357 kernel: Freeing unused kernel image (rodata/data gap) memory: 828K Nov 5 04:44:52.394366 kernel: Run /init as init process Nov 5 04:44:52.394375 kernel: with arguments: Nov 5 04:44:52.394384 kernel: /init Nov 5 04:44:52.394392 kernel: with environment: Nov 5 04:44:52.394402 kernel: HOME=/ Nov 5 04:44:52.394410 kernel: TERM=linux Nov 5 04:44:52.394421 kernel: SCSI subsystem initialized Nov 5 04:44:52.394430 kernel: libata version 3.00 loaded. Nov 5 04:44:52.394626 kernel: ahci 0000:00:1f.2: version 3.0 Nov 5 04:44:52.394639 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Nov 5 04:44:52.394840 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Nov 5 04:44:52.395045 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Nov 5 04:44:52.395414 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Nov 5 04:44:52.395688 kernel: scsi host0: ahci Nov 5 04:44:52.395909 kernel: scsi host1: ahci Nov 5 04:44:52.396218 kernel: scsi host2: ahci Nov 5 04:44:52.396477 kernel: scsi host3: ahci Nov 5 04:44:52.396910 kernel: scsi host4: ahci Nov 5 04:44:52.397135 kernel: scsi host5: ahci Nov 5 04:44:52.397150 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 26 lpm-pol 1 Nov 5 04:44:52.397159 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 26 lpm-pol 1 Nov 5 04:44:52.397168 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 26 lpm-pol 1 Nov 5 04:44:52.397177 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 26 lpm-pol 1 Nov 5 04:44:52.397192 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 26 lpm-pol 1 Nov 5 04:44:52.397200 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 26 lpm-pol 1 Nov 5 04:44:52.397209 kernel: ata6: SATA link down (SStatus 0 SControl 300) Nov 5 04:44:52.397218 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Nov 5 04:44:52.397227 kernel: ata4: SATA link down (SStatus 0 SControl 300) Nov 5 04:44:52.397238 kernel: ata5: SATA link down (SStatus 0 SControl 300) Nov 5 04:44:52.397247 kernel: ata1: SATA link down (SStatus 0 SControl 300) Nov 5 04:44:52.397256 kernel: ata2: SATA link down (SStatus 0 SControl 300) Nov 5 04:44:52.397267 kernel: ata3.00: LPM support broken, forcing max_power Nov 5 04:44:52.397275 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Nov 5 04:44:52.397284 kernel: ata3.00: applying bridge limits Nov 5 04:44:52.397293 kernel: ata3.00: LPM support broken, forcing max_power Nov 5 04:44:52.397311 kernel: ata3.00: configured for UDMA/100 Nov 5 04:44:52.397680 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Nov 5 04:44:52.397950 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Nov 5 04:44:52.398440 kernel: virtio_blk virtio1: [vda] 16515072 512-byte logical blocks (8.46 GB/7.88 GiB) Nov 5 04:44:52.398457 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 04:44:52.399054 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Nov 5 04:44:52.399075 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 5 04:44:52.399353 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Nov 5 04:44:52.399373 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 5 04:44:52.399383 kernel: device-mapper: uevent: version 1.0.3 Nov 5 04:44:52.399393 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 5 04:44:52.399413 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 5 04:44:52.399435 kernel: raid6: avx2x4 gen() 30027 MB/s Nov 5 04:44:52.399452 kernel: raid6: avx2x2 gen() 30052 MB/s Nov 5 04:44:52.399467 kernel: raid6: avx2x1 gen() 25615 MB/s Nov 5 04:44:52.399486 kernel: raid6: using algorithm avx2x2 gen() 30052 MB/s Nov 5 04:44:52.399502 kernel: raid6: .... xor() 19481 MB/s, rmw enabled Nov 5 04:44:52.399527 kernel: raid6: using avx2x2 recovery algorithm Nov 5 04:44:52.399541 kernel: xor: automatically using best checksumming function avx Nov 5 04:44:52.399551 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 5 04:44:52.399560 kernel: BTRFS: device fsid 8119ddf0-7fda-4d84-ad78-3566733896c1 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (181) Nov 5 04:44:52.399570 kernel: BTRFS info (device dm-0): first mount of filesystem 8119ddf0-7fda-4d84-ad78-3566733896c1 Nov 5 04:44:52.399583 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 5 04:44:52.399592 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 5 04:44:52.399609 kernel: BTRFS info (device dm-0): enabling free space tree Nov 5 04:44:52.399619 kernel: loop: module loaded Nov 5 04:44:52.399629 kernel: loop0: detected capacity change from 0 to 100136 Nov 5 04:44:52.399645 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 5 04:44:52.399666 systemd[1]: Successfully made /usr/ read-only. Nov 5 04:44:52.399685 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 5 04:44:52.399700 systemd[1]: Detected virtualization kvm. Nov 5 04:44:52.399725 systemd[1]: Detected architecture x86-64. Nov 5 04:44:52.399742 systemd[1]: Running in initrd. Nov 5 04:44:52.399752 systemd[1]: No hostname configured, using default hostname. Nov 5 04:44:52.399775 systemd[1]: Hostname set to . Nov 5 04:44:52.399811 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 5 04:44:52.399829 systemd[1]: Queued start job for default target initrd.target. Nov 5 04:44:52.399848 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 04:44:52.399858 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 04:44:52.399880 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 04:44:52.399901 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 5 04:44:52.399914 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 04:44:52.399925 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 5 04:44:52.399947 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 5 04:44:52.399959 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 04:44:52.399975 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 04:44:52.400003 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 5 04:44:52.400029 systemd[1]: Reached target paths.target - Path Units. Nov 5 04:44:52.400040 systemd[1]: Reached target slices.target - Slice Units. Nov 5 04:44:52.400051 systemd[1]: Reached target swap.target - Swaps. Nov 5 04:44:52.400060 systemd[1]: Reached target timers.target - Timer Units. Nov 5 04:44:52.400070 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 04:44:52.400079 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 04:44:52.400089 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 5 04:44:52.400102 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 5 04:44:52.400111 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 04:44:52.400121 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 04:44:52.400130 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 04:44:52.400139 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 04:44:52.400149 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 5 04:44:52.400159 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 5 04:44:52.400170 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 04:44:52.400180 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 5 04:44:52.400190 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 5 04:44:52.400200 systemd[1]: Starting systemd-fsck-usr.service... Nov 5 04:44:52.400209 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 04:44:52.400219 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 04:44:52.400230 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 04:44:52.400240 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 5 04:44:52.400250 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 04:44:52.400259 systemd[1]: Finished systemd-fsck-usr.service. Nov 5 04:44:52.400271 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 04:44:52.400345 systemd-journald[316]: Collecting audit messages is disabled. Nov 5 04:44:52.400372 systemd-journald[316]: Journal started Nov 5 04:44:52.400397 systemd-journald[316]: Runtime Journal (/run/log/journal/3d35a656c9cc4407b119f6384ebcdcf9) is 6M, max 48.1M, 42M free. Nov 5 04:44:52.403355 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 04:44:52.406466 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 04:44:52.481034 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:52.488104 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 04:44:52.498804 systemd-tmpfiles[331]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 5 04:44:52.504405 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 5 04:44:52.508180 systemd-modules-load[318]: Inserted module 'br_netfilter' Nov 5 04:44:52.510074 kernel: Bridge firewalling registered Nov 5 04:44:52.510121 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 04:44:52.512568 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 04:44:52.518100 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 04:44:52.524158 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 04:44:52.525268 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 04:44:52.536955 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 04:44:52.538880 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 5 04:44:52.551450 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 04:44:52.556348 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 04:44:52.560518 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 04:44:52.564613 dracut-cmdline[355]: 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=9a076e14dca937d9663502c090e1ff4931f585a3752c3aa4c87feb67d6e5a465 Nov 5 04:44:52.623348 systemd-resolved[367]: Positive Trust Anchors: Nov 5 04:44:52.623360 systemd-resolved[367]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 04:44:52.623364 systemd-resolved[367]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 04:44:52.623394 systemd-resolved[367]: 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 5 04:44:52.641554 systemd-resolved[367]: Defaulting to hostname 'linux'. Nov 5 04:44:52.644901 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 04:44:52.646734 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 04:44:52.679349 kernel: Loading iSCSI transport class v2.0-870. Nov 5 04:44:52.693355 kernel: iscsi: registered transport (tcp) Nov 5 04:44:52.718355 kernel: iscsi: registered transport (qla4xxx) Nov 5 04:44:52.718523 kernel: QLogic iSCSI HBA Driver Nov 5 04:44:52.745915 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 04:44:52.781198 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 04:44:52.785018 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 04:44:52.855780 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 5 04:44:52.859178 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 5 04:44:52.861721 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 5 04:44:52.901755 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 5 04:44:52.906974 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 04:44:52.938967 systemd-udevd[602]: Using default interface naming scheme 'v257'. Nov 5 04:44:52.953985 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 04:44:52.959459 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 5 04:44:52.983728 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 04:44:52.985849 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 04:44:52.999125 dracut-pre-trigger[677]: rd.md=0: removing MD RAID activation Nov 5 04:44:53.030512 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 04:44:53.032042 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 04:44:53.041687 systemd-networkd[703]: lo: Link UP Nov 5 04:44:53.041695 systemd-networkd[703]: lo: Gained carrier Nov 5 04:44:53.042270 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 04:44:53.044677 systemd[1]: Reached target network.target - Network. Nov 5 04:44:53.127578 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 04:44:53.131007 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 5 04:44:53.184963 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 5 04:44:53.225366 kernel: cryptd: max_cpu_qlen set to 1000 Nov 5 04:44:53.227681 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 5 04:44:53.242667 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 5 04:44:53.254375 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Nov 5 04:44:53.256356 kernel: AES CTR mode by8 optimization enabled Nov 5 04:44:53.256877 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 5 04:44:53.269799 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 5 04:44:53.270794 systemd-networkd[703]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 04:44:53.270799 systemd-networkd[703]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 04:44:53.271230 systemd-networkd[703]: eth0: Link UP Nov 5 04:44:53.272251 systemd-networkd[703]: eth0: Gained carrier Nov 5 04:44:53.272261 systemd-networkd[703]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 04:44:53.275985 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 04:44:53.276203 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:53.283913 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 04:44:53.289561 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 04:44:53.292980 systemd-networkd[703]: eth0: DHCPv4 address 10.0.0.29/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 5 04:44:53.346119 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 04:44:53.346241 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:53.357521 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 04:44:53.385534 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 5 04:44:53.386238 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 04:44:53.386730 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 04:44:53.387253 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 04:44:53.388964 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 5 04:44:53.425645 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:53.440035 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 5 04:44:54.344610 disk-uuid[834]: Warning: The kernel is still using the old partition table. Nov 5 04:44:54.344610 disk-uuid[834]: The new table will be used at the next reboot or after you Nov 5 04:44:54.344610 disk-uuid[834]: run partprobe(8) or kpartx(8) Nov 5 04:44:54.344610 disk-uuid[834]: The operation has completed successfully. Nov 5 04:44:54.360557 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 04:44:54.362115 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 5 04:44:54.367175 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 5 04:44:54.414365 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (872) Nov 5 04:44:54.417548 kernel: BTRFS info (device vda6): first mount of filesystem e7137982-ac37-41c2-8fd6-d0cf0728ebd4 Nov 5 04:44:54.417570 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 5 04:44:54.421369 kernel: BTRFS info (device vda6): turning on async discard Nov 5 04:44:54.421389 kernel: BTRFS info (device vda6): enabling free space tree Nov 5 04:44:54.429362 kernel: BTRFS info (device vda6): last unmount of filesystem e7137982-ac37-41c2-8fd6-d0cf0728ebd4 Nov 5 04:44:54.430719 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 5 04:44:54.433777 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 5 04:44:54.627791 ignition[891]: Ignition 2.22.0 Nov 5 04:44:54.627806 ignition[891]: Stage: fetch-offline Nov 5 04:44:54.627853 ignition[891]: no configs at "/usr/lib/ignition/base.d" Nov 5 04:44:54.627869 ignition[891]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 04:44:54.627981 ignition[891]: parsed url from cmdline: "" Nov 5 04:44:54.627986 ignition[891]: no config URL provided Nov 5 04:44:54.627991 ignition[891]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 04:44:54.628004 ignition[891]: no config at "/usr/lib/ignition/user.ign" Nov 5 04:44:54.628055 ignition[891]: op(1): [started] loading QEMU firmware config module Nov 5 04:44:54.628063 ignition[891]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 5 04:44:54.648595 ignition[891]: op(1): [finished] loading QEMU firmware config module Nov 5 04:44:54.649862 ignition[891]: parsing config with SHA512: fa456b58ed04e5ac3f99288f5cd30306029a23366b8f2b97bc792f9f3ef9784326fcbab19a1d84d5822619b1bb4c2ef9605719c4a6b2a0ac244d69696c8eecb1 Nov 5 04:44:54.650486 systemd-networkd[703]: eth0: Gained IPv6LL Nov 5 04:44:54.662246 unknown[891]: fetched base config from "system" Nov 5 04:44:54.662480 unknown[891]: fetched user config from "qemu" Nov 5 04:44:54.662688 ignition[891]: fetch-offline: fetch-offline passed Nov 5 04:44:54.666359 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 04:44:54.662828 ignition[891]: Ignition finished successfully Nov 5 04:44:54.669827 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 5 04:44:54.676280 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 5 04:44:54.736131 ignition[902]: Ignition 2.22.0 Nov 5 04:44:54.736151 ignition[902]: Stage: kargs Nov 5 04:44:54.736609 ignition[902]: no configs at "/usr/lib/ignition/base.d" Nov 5 04:44:54.736623 ignition[902]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 04:44:54.737299 ignition[902]: kargs: kargs passed Nov 5 04:44:54.742793 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 5 04:44:54.737378 ignition[902]: Ignition finished successfully Nov 5 04:44:54.745961 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 5 04:44:54.781418 ignition[910]: Ignition 2.22.0 Nov 5 04:44:54.781432 ignition[910]: Stage: disks Nov 5 04:44:54.781584 ignition[910]: no configs at "/usr/lib/ignition/base.d" Nov 5 04:44:54.781596 ignition[910]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 04:44:54.785520 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 5 04:44:54.782109 ignition[910]: disks: disks passed Nov 5 04:44:54.782159 ignition[910]: Ignition finished successfully Nov 5 04:44:54.791618 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 5 04:44:54.794211 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 5 04:44:54.794826 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 04:44:54.801204 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 04:44:54.802765 systemd[1]: Reached target basic.target - Basic System. Nov 5 04:44:54.808897 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 5 04:44:54.862109 systemd-fsck[920]: ROOT: clean, 15/456736 files, 38230/456704 blocks Nov 5 04:44:54.869804 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 5 04:44:54.871304 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 5 04:44:55.033366 kernel: EXT4-fs (vda9): mounted filesystem d6ba737d-b2ad-4de6-9309-ffb105e40987 r/w with ordered data mode. Quota mode: none. Nov 5 04:44:55.034516 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 5 04:44:55.035316 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 5 04:44:55.040409 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 04:44:55.043727 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 5 04:44:55.045453 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 5 04:44:55.045501 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 5 04:44:55.045533 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 04:44:55.063999 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 5 04:44:55.068668 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (929) Nov 5 04:44:55.069752 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 5 04:44:55.078355 kernel: BTRFS info (device vda6): first mount of filesystem e7137982-ac37-41c2-8fd6-d0cf0728ebd4 Nov 5 04:44:55.078393 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 5 04:44:55.078409 kernel: BTRFS info (device vda6): turning on async discard Nov 5 04:44:55.078471 kernel: BTRFS info (device vda6): enabling free space tree Nov 5 04:44:55.077912 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 04:44:55.132319 initrd-setup-root[953]: cut: /sysroot/etc/passwd: No such file or directory Nov 5 04:44:55.137977 initrd-setup-root[960]: cut: /sysroot/etc/group: No such file or directory Nov 5 04:44:55.143014 initrd-setup-root[967]: cut: /sysroot/etc/shadow: No such file or directory Nov 5 04:44:55.148925 initrd-setup-root[974]: cut: /sysroot/etc/gshadow: No such file or directory Nov 5 04:44:55.249645 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 5 04:44:55.251433 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 5 04:44:55.255449 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 5 04:44:55.280364 kernel: BTRFS info (device vda6): last unmount of filesystem e7137982-ac37-41c2-8fd6-d0cf0728ebd4 Nov 5 04:44:55.293498 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 5 04:44:55.316048 ignition[1043]: INFO : Ignition 2.22.0 Nov 5 04:44:55.316048 ignition[1043]: INFO : Stage: mount Nov 5 04:44:55.318993 ignition[1043]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 04:44:55.318993 ignition[1043]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 04:44:55.318993 ignition[1043]: INFO : mount: mount passed Nov 5 04:44:55.318993 ignition[1043]: INFO : Ignition finished successfully Nov 5 04:44:55.319525 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 5 04:44:55.323376 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 5 04:44:55.404827 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 5 04:44:55.407159 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 04:44:55.445754 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1055) Nov 5 04:44:55.445828 kernel: BTRFS info (device vda6): first mount of filesystem e7137982-ac37-41c2-8fd6-d0cf0728ebd4 Nov 5 04:44:55.445840 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 5 04:44:55.450965 kernel: BTRFS info (device vda6): turning on async discard Nov 5 04:44:55.450985 kernel: BTRFS info (device vda6): enabling free space tree Nov 5 04:44:55.452812 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 04:44:55.505863 ignition[1072]: INFO : Ignition 2.22.0 Nov 5 04:44:55.505863 ignition[1072]: INFO : Stage: files Nov 5 04:44:55.508838 ignition[1072]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 04:44:55.508838 ignition[1072]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 04:44:55.508838 ignition[1072]: DEBUG : files: compiled without relabeling support, skipping Nov 5 04:44:55.508838 ignition[1072]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 5 04:44:55.508838 ignition[1072]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 5 04:44:55.519658 ignition[1072]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 5 04:44:55.519658 ignition[1072]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 5 04:44:55.519658 ignition[1072]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 04:44:55.519658 ignition[1072]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 04:44:55.519658 ignition[1072]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 04:44:55.519658 ignition[1072]: INFO : files: op(4): [started] processing unit "etcd-member.service" Nov 5 04:44:55.519658 ignition[1072]: 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 5 04:44:55.511704 unknown[1072]: wrote ssh authorized keys file for user: core Nov 5 04:44:55.541971 ignition[1072]: 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 5 04:44:55.541971 ignition[1072]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Nov 5 04:44:55.541971 ignition[1072]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Nov 5 04:44:55.541971 ignition[1072]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 5 04:44:55.541971 ignition[1072]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 5 04:44:55.541971 ignition[1072]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Nov 5 04:44:55.541971 ignition[1072]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Nov 5 04:44:55.541971 ignition[1072]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 5 04:44:55.549077 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 5 04:44:55.570149 ignition[1072]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 5 04:44:55.570149 ignition[1072]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Nov 5 04:44:55.570149 ignition[1072]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Nov 5 04:44:55.570149 ignition[1072]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Nov 5 04:44:55.570149 ignition[1072]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 5 04:44:55.570149 ignition[1072]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 5 04:44:55.570149 ignition[1072]: INFO : files: files passed Nov 5 04:44:55.570149 ignition[1072]: INFO : Ignition finished successfully Nov 5 04:44:55.552667 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 5 04:44:55.557008 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 5 04:44:55.573234 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 04:44:55.595637 initrd-setup-root-after-ignition[1102]: grep: /sysroot/oem/oem-release: No such file or directory Nov 5 04:44:55.573404 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 5 04:44:55.599900 initrd-setup-root-after-ignition[1105]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 04:44:55.599900 initrd-setup-root-after-ignition[1105]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 5 04:44:55.599335 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 04:44:55.612085 initrd-setup-root-after-ignition[1109]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 04:44:55.602015 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 5 04:44:55.606892 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 5 04:44:55.665600 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 04:44:55.665746 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 5 04:44:55.667565 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 5 04:44:55.672734 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 5 04:44:55.676250 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 5 04:44:55.677396 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 5 04:44:55.723949 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 04:44:55.728105 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 5 04:44:55.754768 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 04:44:55.754903 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 5 04:44:55.758654 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 04:44:55.762319 systemd[1]: Stopped target timers.target - Timer Units. Nov 5 04:44:55.764161 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 04:44:55.764286 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 04:44:55.770127 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 5 04:44:55.773547 systemd[1]: Stopped target basic.target - Basic System. Nov 5 04:44:55.775197 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 5 04:44:55.779483 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 04:44:55.781217 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 5 04:44:55.786297 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 5 04:44:55.788061 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 5 04:44:55.788867 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 04:44:55.796292 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 5 04:44:55.798261 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 5 04:44:55.802760 systemd[1]: Stopped target swap.target - Swaps. Nov 5 04:44:55.804347 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 04:44:55.804461 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 5 04:44:55.809821 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 5 04:44:55.813123 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 04:44:55.814790 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 5 04:44:55.815050 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 04:44:55.820268 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 04:44:55.820398 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 5 04:44:55.825415 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 5 04:44:55.825537 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 04:44:55.827077 systemd[1]: Stopped target paths.target - Path Units. Nov 5 04:44:55.827795 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 04:44:55.836474 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 04:44:55.842228 systemd[1]: Stopped target slices.target - Slice Units. Nov 5 04:44:55.842432 systemd[1]: Stopped target sockets.target - Socket Units. Nov 5 04:44:55.845152 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 04:44:55.845278 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 04:44:55.845963 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 04:44:55.846049 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 04:44:55.850622 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 5 04:44:55.850756 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 04:44:55.855487 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 04:44:55.855603 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 5 04:44:55.861641 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 5 04:44:55.867927 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 5 04:44:55.869409 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 5 04:44:55.869551 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 04:44:55.872238 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 04:44:55.872364 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 04:44:55.873012 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 04:44:55.873112 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 04:44:55.894030 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 04:44:55.894165 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 5 04:44:55.899269 ignition[1129]: INFO : Ignition 2.22.0 Nov 5 04:44:55.899269 ignition[1129]: INFO : Stage: umount Nov 5 04:44:55.899269 ignition[1129]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 04:44:55.899269 ignition[1129]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 04:44:55.899269 ignition[1129]: INFO : umount: umount passed Nov 5 04:44:55.899269 ignition[1129]: INFO : Ignition finished successfully Nov 5 04:44:55.898509 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 5 04:44:55.898639 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 5 04:44:55.901059 systemd[1]: Stopped target network.target - Network. Nov 5 04:44:55.902054 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 04:44:55.902116 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 5 04:44:55.906172 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 5 04:44:55.906237 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 5 04:44:55.911200 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 04:44:55.911270 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 5 04:44:55.915400 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 5 04:44:55.915454 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 5 04:44:55.918598 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 5 04:44:55.920101 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 5 04:44:55.928611 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 5 04:44:55.929446 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 04:44:55.929580 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 5 04:44:55.932895 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 04:44:55.932978 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 5 04:44:55.933925 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 04:44:55.934062 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 5 04:44:55.941580 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 04:44:55.941710 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 5 04:44:55.948128 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 5 04:44:55.949666 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 04:44:55.949753 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 5 04:44:55.953844 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 5 04:44:55.955769 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 5 04:44:55.955836 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 04:44:55.960371 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 04:44:55.960426 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 5 04:44:55.963916 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 5 04:44:55.963973 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 5 04:44:55.964766 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 04:44:55.998224 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 04:44:55.998454 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 04:44:56.002188 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 04:44:56.002296 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 5 04:44:56.004296 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 04:44:56.004361 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 04:44:56.008945 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 04:44:56.009007 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 5 04:44:56.011507 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 04:44:56.011568 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 5 04:44:56.020839 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 04:44:56.020929 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 04:44:56.031116 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 5 04:44:56.031207 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 5 04:44:56.031276 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 04:44:56.031796 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 04:44:56.031846 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 04:44:56.032046 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 5 04:44:56.032091 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 04:44:56.032343 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 04:44:56.032388 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 04:44:56.032596 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 04:44:56.032641 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:56.043743 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 04:44:56.043852 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 5 04:44:56.046056 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 04:44:56.046173 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 5 04:44:56.049541 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 5 04:44:56.053719 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 5 04:44:56.068445 systemd[1]: Switching root. Nov 5 04:44:56.096736 systemd-journald[316]: Journal stopped Nov 5 04:44:57.049768 systemd-journald[316]: Received SIGTERM from PID 1 (systemd). Nov 5 04:44:57.049831 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 04:44:57.049860 kernel: SELinux: policy capability open_perms=1 Nov 5 04:44:57.049877 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 04:44:57.049889 kernel: SELinux: policy capability always_check_network=0 Nov 5 04:44:57.049906 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 04:44:57.049924 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 04:44:57.049936 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 04:44:57.049948 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 5 04:44:57.050275 kernel: SELinux: policy capability userspace_initial_context=0 Nov 5 04:44:57.050293 kernel: audit: type=1403 audit(1762317896.187:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 04:44:57.050312 systemd[1]: Successfully loaded SELinux policy in 69.500ms. Nov 5 04:44:57.050346 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.823ms. Nov 5 04:44:57.050366 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 5 04:44:57.050380 systemd[1]: Detected virtualization kvm. Nov 5 04:44:57.050402 systemd[1]: Detected architecture x86-64. Nov 5 04:44:57.050416 systemd[1]: Detected first boot. Nov 5 04:44:57.050428 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 5 04:44:57.050445 zram_generator::config[1174]: No configuration found. Nov 5 04:44:57.050459 kernel: Guest personality initialized and is inactive Nov 5 04:44:57.050471 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Nov 5 04:44:57.050483 kernel: Initialized host personality Nov 5 04:44:57.050503 kernel: NET: Registered PF_VSOCK protocol family Nov 5 04:44:57.050516 systemd[1]: Populated /etc with preset unit settings. Nov 5 04:44:57.050530 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 04:44:57.050543 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 5 04:44:57.050556 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 04:44:57.050570 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 5 04:44:57.050590 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 5 04:44:57.050605 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 5 04:44:57.050618 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 5 04:44:57.050636 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 5 04:44:57.050648 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 5 04:44:57.050669 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 5 04:44:57.050683 systemd[1]: Created slice user.slice - User and Session Slice. Nov 5 04:44:57.050696 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 04:44:57.050709 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 04:44:57.050722 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 5 04:44:57.050736 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 5 04:44:57.050749 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 5 04:44:57.050771 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 04:44:57.050784 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 5 04:44:57.050797 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 04:44:57.050810 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 04:44:57.050823 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 5 04:44:57.050836 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 5 04:44:57.050848 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 5 04:44:57.050868 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 5 04:44:57.050881 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 04:44:57.050894 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 04:44:57.050907 systemd[1]: Reached target slices.target - Slice Units. Nov 5 04:44:57.050919 systemd[1]: Reached target swap.target - Swaps. Nov 5 04:44:57.050932 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 5 04:44:57.050945 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 5 04:44:57.050965 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 5 04:44:57.050978 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 04:44:57.050992 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 04:44:57.051005 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 04:44:57.051017 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 5 04:44:57.051030 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 5 04:44:57.051043 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 5 04:44:57.051062 systemd[1]: Mounting media.mount - External Media Directory... Nov 5 04:44:57.051076 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 04:44:57.051088 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 5 04:44:57.051101 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 5 04:44:57.051114 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 5 04:44:57.051127 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 5 04:44:57.051140 systemd[1]: Reached target machines.target - Containers. Nov 5 04:44:57.051159 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 5 04:44:57.051180 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 04:44:57.051194 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 04:44:57.051207 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 5 04:44:57.051219 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 04:44:57.051232 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 04:44:57.051244 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 04:44:57.051265 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 5 04:44:57.051278 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 04:44:57.051291 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 5 04:44:57.051307 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 04:44:57.051334 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 5 04:44:57.051347 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 04:44:57.051369 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 04:44:57.051383 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 5 04:44:57.051396 kernel: fuse: init (API version 7.41) Nov 5 04:44:57.051408 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 04:44:57.051421 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 04:44:57.051434 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 04:44:57.051447 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 5 04:44:57.051468 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 5 04:44:57.051481 kernel: ACPI: bus type drm_connector registered Nov 5 04:44:57.051494 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 04:44:57.051508 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 04:44:57.051528 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 5 04:44:57.051542 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 5 04:44:57.051554 systemd[1]: Mounted media.mount - External Media Directory. Nov 5 04:44:57.051586 systemd-journald[1252]: Collecting audit messages is disabled. Nov 5 04:44:57.051610 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 5 04:44:57.051623 systemd-journald[1252]: Journal started Nov 5 04:44:57.051653 systemd-journald[1252]: Runtime Journal (/run/log/journal/3d35a656c9cc4407b119f6384ebcdcf9) is 6M, max 48.1M, 42M free. Nov 5 04:44:56.735702 systemd[1]: Queued start job for default target multi-user.target. Nov 5 04:44:56.755304 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 5 04:44:56.755851 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 04:44:57.053355 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 04:44:57.056066 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 5 04:44:57.057978 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 5 04:44:57.059922 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 5 04:44:57.062156 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 04:44:57.064486 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 04:44:57.064723 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 5 04:44:57.066928 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 04:44:57.067154 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 04:44:57.069309 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 04:44:57.069563 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 04:44:57.071649 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 04:44:57.071891 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 04:44:57.074129 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 04:44:57.074381 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 5 04:44:57.076537 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 04:44:57.076760 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 04:44:57.078855 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 04:44:57.081075 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 04:44:57.084133 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 5 04:44:57.086641 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 5 04:44:57.102466 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 04:44:57.105063 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 5 04:44:57.108302 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 5 04:44:57.111168 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 5 04:44:57.112947 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 5 04:44:57.112976 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 04:44:57.113938 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 5 04:44:57.116740 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 04:44:57.120990 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 5 04:44:57.123859 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 5 04:44:57.125912 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 04:44:57.127036 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 5 04:44:57.128870 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 04:44:57.130059 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 04:44:57.133537 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 5 04:44:57.136610 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 04:44:57.138377 systemd-journald[1252]: Time spent on flushing to /var/log/journal/3d35a656c9cc4407b119f6384ebcdcf9 is 15.071ms for 1028 entries. Nov 5 04:44:57.138377 systemd-journald[1252]: System Journal (/var/log/journal/3d35a656c9cc4407b119f6384ebcdcf9) is 8M, max 163.5M, 155.5M free. Nov 5 04:44:57.174116 systemd-journald[1252]: Received client request to flush runtime journal. Nov 5 04:44:57.144936 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 04:44:57.149159 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 5 04:44:57.151159 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 5 04:44:57.169992 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 5 04:44:57.172007 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 5 04:44:57.176255 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 5 04:44:57.181869 kernel: loop1: detected capacity change from 0 to 119080 Nov 5 04:44:57.180178 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 5 04:44:57.181617 systemd-tmpfiles[1294]: ACLs are not supported, ignoring. Nov 5 04:44:57.181631 systemd-tmpfiles[1294]: ACLs are not supported, ignoring. Nov 5 04:44:57.184732 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 04:44:57.187680 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 04:44:57.194564 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 5 04:44:57.209749 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 5 04:44:57.221353 kernel: loop2: detected capacity change from 0 to 111544 Nov 5 04:44:57.235133 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 5 04:44:57.239215 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 04:44:57.241857 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 04:44:57.250357 kernel: loop3: detected capacity change from 0 to 119080 Nov 5 04:44:57.256550 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 5 04:44:57.259380 kernel: loop4: detected capacity change from 0 to 111544 Nov 5 04:44:57.268287 (sd-merge)[1316]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw'. Nov 5 04:44:57.272424 (sd-merge)[1316]: Merged extensions into '/usr'. Nov 5 04:44:57.273122 systemd-tmpfiles[1315]: ACLs are not supported, ignoring. Nov 5 04:44:57.273135 systemd-tmpfiles[1315]: ACLs are not supported, ignoring. Nov 5 04:44:57.276813 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 5 04:44:57.279458 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 04:44:57.284941 systemd[1]: Starting ensure-sysext.service... Nov 5 04:44:57.290837 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 04:44:57.296691 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 5 04:44:57.310066 systemd[1]: Reload requested from client PID 1321 ('systemctl') (unit ensure-sysext.service)... Nov 5 04:44:57.310085 systemd[1]: Reloading... Nov 5 04:44:57.322981 systemd-tmpfiles[1322]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 5 04:44:57.323016 systemd-tmpfiles[1322]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 5 04:44:57.323339 systemd-tmpfiles[1322]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 5 04:44:57.323952 systemd-tmpfiles[1322]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 5 04:44:57.324899 systemd-tmpfiles[1322]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 04:44:57.325235 systemd-tmpfiles[1322]: ACLs are not supported, ignoring. Nov 5 04:44:57.325417 systemd-tmpfiles[1322]: ACLs are not supported, ignoring. Nov 5 04:44:57.330587 systemd-tmpfiles[1322]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 04:44:57.330672 systemd-tmpfiles[1322]: Skipping /boot Nov 5 04:44:57.341596 systemd-tmpfiles[1322]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 04:44:57.341684 systemd-tmpfiles[1322]: Skipping /boot Nov 5 04:44:57.382424 zram_generator::config[1364]: No configuration found. Nov 5 04:44:57.392901 systemd-resolved[1314]: Positive Trust Anchors: Nov 5 04:44:57.392917 systemd-resolved[1314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 04:44:57.392922 systemd-resolved[1314]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 04:44:57.392953 systemd-resolved[1314]: 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 5 04:44:57.397393 systemd-resolved[1314]: Defaulting to hostname 'linux'. Nov 5 04:44:57.564304 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 04:44:57.564727 systemd[1]: Reloading finished in 254 ms. Nov 5 04:44:57.601387 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 04:44:57.634470 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 04:44:57.641991 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 04:44:57.645274 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 04:44:57.648000 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 5 04:44:57.651589 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 5 04:44:57.656458 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 5 04:44:57.659605 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 5 04:44:57.664126 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 04:44:57.665393 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 04:44:57.668228 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 04:44:57.671706 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 04:44:57.673508 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 04:44:57.673632 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 5 04:44:57.676084 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 04:44:57.676310 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 04:44:57.676458 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 5 04:44:57.679354 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 04:44:57.681222 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 04:44:57.684481 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 04:44:57.684625 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 5 04:44:57.689866 systemd[1]: Finished ensure-sysext.service. Nov 5 04:44:57.696952 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 5 04:44:57.706152 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 04:44:57.707372 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 04:44:57.710316 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 5 04:44:57.714850 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 04:44:57.716270 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 04:44:57.718706 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 04:44:57.718910 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 04:44:57.723840 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 04:44:57.724055 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 04:44:57.729214 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 04:44:57.729301 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 04:44:57.734254 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 5 04:44:57.740474 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 04:44:57.758367 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 04:44:57.758389 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 04:44:57.784840 augenrules[1436]: No rules Nov 5 04:44:57.785624 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 5 04:44:57.790546 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 04:44:57.790818 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 04:44:57.793012 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 5 04:44:57.797665 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 5 04:44:57.812778 systemd-udevd[1426]: Using default interface naming scheme 'v257'. Nov 5 04:44:57.837987 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 5 04:44:57.840368 systemd[1]: Reached target time-set.target - System Time Set. Nov 5 04:44:57.842254 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 04:44:57.848237 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 04:44:58.009984 kernel: mousedev: PS/2 mouse device common for all mice Nov 5 04:44:58.002397 systemd-networkd[1453]: lo: Link UP Nov 5 04:44:58.002402 systemd-networkd[1453]: lo: Gained carrier Nov 5 04:44:58.006122 systemd-networkd[1453]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 04:44:58.006127 systemd-networkd[1453]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 04:44:58.006416 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 04:44:58.008528 systemd-networkd[1453]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 04:44:58.008589 systemd-networkd[1453]: eth0: Link UP Nov 5 04:44:58.009256 systemd-networkd[1453]: eth0: Gained carrier Nov 5 04:44:58.009270 systemd-networkd[1453]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 04:44:58.009908 systemd[1]: Reached target network.target - Network. Nov 5 04:44:58.037387 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 5 04:44:58.040436 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 5 04:44:58.048423 systemd-networkd[1453]: eth0: DHCPv4 address 10.0.0.29/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 5 04:44:58.049969 systemd-timesyncd[1407]: Network configuration changed, trying to establish connection. Nov 5 04:44:58.618738 systemd-resolved[1314]: Clock change detected. Flushing caches. Nov 5 04:44:58.618823 systemd-timesyncd[1407]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 5 04:44:58.618865 systemd-timesyncd[1407]: Initial clock synchronization to Wed 2025-11-05 04:44:58.618697 UTC. Nov 5 04:44:58.622187 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 5 04:44:58.622602 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 Nov 5 04:44:58.628545 kernel: ACPI: button: Power Button [PWRF] Nov 5 04:44:58.634645 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 5 04:44:58.638636 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 5 04:44:58.649282 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 5 04:44:58.660105 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 5 04:44:58.670857 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Nov 5 04:44:58.671202 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Nov 5 04:44:58.672914 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Nov 5 04:44:59.715996 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 04:44:59.724197 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 04:44:59.724472 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:59.728179 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 04:44:59.736248 kernel: kvm_amd: TSC scaling supported Nov 5 04:44:59.736378 kernel: kvm_amd: Nested Virtualization enabled Nov 5 04:44:59.736407 kernel: kvm_amd: Nested Paging enabled Nov 5 04:44:59.736439 kernel: kvm_amd: LBR virtualization supported Nov 5 04:44:59.736470 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Nov 5 04:44:59.736491 kernel: kvm_amd: Virtual GIF supported Nov 5 04:44:59.779748 kernel: EDAC MC: Ver: 3.0.0 Nov 5 04:44:59.796750 ldconfig[1398]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 04:44:59.807402 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 5 04:44:59.809200 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 5 04:44:59.823026 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 04:44:59.832122 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 5 04:44:59.834120 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 04:44:59.835916 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 5 04:44:59.838023 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 5 04:44:59.840221 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 5 04:44:59.842387 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 5 04:44:59.844423 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 5 04:44:59.846535 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 5 04:44:59.848590 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 5 04:44:59.848625 systemd[1]: Reached target paths.target - Path Units. Nov 5 04:44:59.850096 systemd[1]: Reached target timers.target - Timer Units. Nov 5 04:44:59.852869 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 5 04:44:59.856720 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 5 04:44:59.860412 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 5 04:44:59.862607 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 5 04:44:59.864618 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 5 04:44:59.868988 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 5 04:44:59.870935 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 5 04:44:59.873353 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 5 04:44:59.875763 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 04:44:59.877300 systemd[1]: Reached target basic.target - Basic System. Nov 5 04:44:59.878846 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 5 04:44:59.878876 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 5 04:44:59.879914 systemd[1]: Starting containerd.service - containerd container runtime... Nov 5 04:44:59.882738 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 5 04:44:59.885307 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 5 04:44:59.888645 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 5 04:44:59.891382 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 5 04:44:59.893024 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 5 04:44:59.894114 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 5 04:44:59.898187 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 5 04:44:59.901393 jq[1514]: false Nov 5 04:44:59.902842 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 5 04:44:59.907792 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 5 04:44:59.917053 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Refreshing passwd entry cache Nov 5 04:44:59.915752 oslogin_cache_refresh[1516]: Refreshing passwd entry cache Nov 5 04:44:59.921707 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 5 04:44:59.923927 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 5 04:44:59.924898 extend-filesystems[1515]: Found /dev/vda6 Nov 5 04:44:59.929553 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Failure getting users, quitting Nov 5 04:44:59.929553 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 5 04:44:59.929553 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Refreshing group entry cache Nov 5 04:44:59.929334 oslogin_cache_refresh[1516]: Failure getting users, quitting Nov 5 04:44:59.929383 oslogin_cache_refresh[1516]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 5 04:44:59.929509 oslogin_cache_refresh[1516]: Refreshing group entry cache Nov 5 04:44:59.930241 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 04:44:59.932941 systemd[1]: Starting update-engine.service - Update Engine... Nov 5 04:44:59.942662 extend-filesystems[1515]: Found /dev/vda9 Nov 5 04:44:59.940770 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 5 04:44:59.944729 oslogin_cache_refresh[1516]: Failure getting groups, quitting Nov 5 04:44:59.949237 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Failure getting groups, quitting Nov 5 04:44:59.949237 google_oslogin_nss_cache[1516]: oslogin_cache_refresh[1516]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 5 04:44:59.948939 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 5 04:44:59.944743 oslogin_cache_refresh[1516]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 5 04:44:59.953654 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 5 04:44:59.954794 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 5 04:44:59.956382 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 5 04:44:59.957200 extend-filesystems[1515]: Checking size of /dev/vda9 Nov 5 04:44:59.957333 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 5 04:44:59.962645 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 04:44:59.966788 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 5 04:44:59.970998 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 04:44:59.972720 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 5 04:44:59.975920 jq[1531]: true Nov 5 04:44:59.982083 update_engine[1530]: I20251105 04:44:59.980887 1530 main.cc:92] Flatcar Update Engine starting Nov 5 04:44:59.982412 extend-filesystems[1515]: Resized partition /dev/vda9 Nov 5 04:45:00.389492 extend-filesystems[1552]: resize2fs 1.47.3 (8-Jul-2025) Nov 5 04:45:00.395267 systemd-networkd[1453]: eth0: Gained IPv6LL Nov 5 04:45:00.402585 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 474107 blocks Nov 5 04:45:00.406388 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 5 04:45:00.434531 kernel: EXT4-fs (vda9): resized filesystem to 474107 Nov 5 04:45:00.434597 jq[1548]: true Nov 5 04:45:00.427179 systemd[1]: Reached target network-online.target - Network is Online. Nov 5 04:45:00.434841 extend-filesystems[1552]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 5 04:45:00.434841 extend-filesystems[1552]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 5 04:45:00.434841 extend-filesystems[1552]: The filesystem on /dev/vda9 is now 474107 (4k) blocks long. Nov 5 04:45:00.441382 extend-filesystems[1515]: Resized filesystem in /dev/vda9 Nov 5 04:45:00.442918 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 5 04:45:00.447723 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 5 04:45:00.451019 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 04:45:00.451391 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 5 04:45:00.467106 systemd-logind[1524]: Watching system buttons on /dev/input/event2 (Power Button) Nov 5 04:45:00.467136 systemd-logind[1524]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 5 04:45:00.467906 systemd-logind[1524]: New seat seat0. Nov 5 04:45:00.472772 systemd[1]: Started systemd-logind.service - User Login Management. Nov 5 04:45:00.488943 dbus-daemon[1512]: [system] SELinux support is enabled Nov 5 04:45:00.489176 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 5 04:45:00.494839 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 5 04:45:00.494875 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 5 04:45:00.497086 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 5 04:45:00.497110 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 5 04:45:00.503293 dbus-daemon[1512]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 5 04:45:00.504024 bash[1580]: Updated "/home/core/.ssh/authorized_keys" Nov 5 04:45:00.504660 update_engine[1530]: I20251105 04:45:00.504563 1530 update_check_scheduler.cc:74] Next update check in 3m25s Nov 5 04:45:00.504718 systemd[1]: Started update-engine.service - Update Engine. Nov 5 04:45:00.506995 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 5 04:45:00.511225 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 5 04:45:00.528287 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 5 04:45:00.531069 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 5 04:45:00.533263 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 5 04:45:00.533534 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 5 04:45:00.545978 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Nov 5 04:45:00.547919 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 5 04:45:00.608798 (-wrapper)[1599]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Nov 5 04:45:00.655083 locksmithd[1592]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 04:45:00.807603 sshd_keygen[1539]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 5 04:45:00.817396 containerd[1546]: time="2025-11-05T04:45:00Z" 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 5 04:45:00.819419 containerd[1546]: time="2025-11-05T04:45:00.819355198Z" level=info msg="starting containerd" revision=75cb2b7193e4e490e9fbdc236c0e811ccaba3376 version=v2.1.4 Nov 5 04:45:00.842393 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 5 04:45:00.852106 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 5 04:45:00.857882 containerd[1546]: time="2025-11-05T04:45:00.857824348Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="22.462µs" Nov 5 04:45:00.857882 containerd[1546]: time="2025-11-05T04:45:00.857873741Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 5 04:45:00.858021 containerd[1546]: time="2025-11-05T04:45:00.857937560Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 5 04:45:00.858021 containerd[1546]: time="2025-11-05T04:45:00.857959612Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 5 04:45:00.858220 containerd[1546]: time="2025-11-05T04:45:00.858197788Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 5 04:45:00.858220 containerd[1546]: time="2025-11-05T04:45:00.858220711Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 04:45:00.858424 containerd[1546]: time="2025-11-05T04:45:00.858305671Z" 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 5 04:45:00.858424 containerd[1546]: time="2025-11-05T04:45:00.858323554Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 04:45:00.858680 containerd[1546]: time="2025-11-05T04:45:00.858633746Z" 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 5 04:45:00.858680 containerd[1546]: time="2025-11-05T04:45:00.858662099Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 04:45:00.858680 containerd[1546]: time="2025-11-05T04:45:00.858676796Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 04:45:00.858788 containerd[1546]: time="2025-11-05T04:45:00.858685954Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 5 04:45:00.858920 containerd[1546]: time="2025-11-05T04:45:00.858884346Z" 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 5 04:45:00.858920 containerd[1546]: time="2025-11-05T04:45:00.858906227Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 5 04:45:00.859043 containerd[1546]: time="2025-11-05T04:45:00.859014980Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 5 04:45:00.859780 containerd[1546]: time="2025-11-05T04:45:00.859738998Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 04:45:00.859973 containerd[1546]: time="2025-11-05T04:45:00.859948952Z" 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 5 04:45:00.860034 containerd[1546]: time="2025-11-05T04:45:00.860014685Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 5 04:45:00.860138 containerd[1546]: time="2025-11-05T04:45:00.860112839Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 5 04:45:00.860487 containerd[1546]: time="2025-11-05T04:45:00.860428912Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 5 04:45:00.860712 containerd[1546]: time="2025-11-05T04:45:00.860678309Z" level=info msg="metadata content store policy set" policy=shared Nov 5 04:45:00.868132 containerd[1546]: time="2025-11-05T04:45:00.868088463Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 5 04:45:00.868204 containerd[1546]: time="2025-11-05T04:45:00.868164947Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 5 04:45:00.868376 containerd[1546]: time="2025-11-05T04:45:00.868342970Z" 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 5 04:45:00.868376 containerd[1546]: time="2025-11-05T04:45:00.868366084Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 5 04:45:00.868425 containerd[1546]: time="2025-11-05T04:45:00.868385600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 5 04:45:00.868425 containerd[1546]: time="2025-11-05T04:45:00.868398434Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 5 04:45:00.868425 containerd[1546]: time="2025-11-05T04:45:00.868409635Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 5 04:45:00.868425 containerd[1546]: time="2025-11-05T04:45:00.868419584Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 5 04:45:00.868519 containerd[1546]: time="2025-11-05T04:45:00.868431206Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 5 04:45:00.868519 containerd[1546]: time="2025-11-05T04:45:00.868444431Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 5 04:45:00.868519 containerd[1546]: time="2025-11-05T04:45:00.868459389Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 5 04:45:00.868519 containerd[1546]: time="2025-11-05T04:45:00.868471842Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 5 04:45:00.868519 containerd[1546]: time="2025-11-05T04:45:00.868483284Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 5 04:45:00.868519 containerd[1546]: time="2025-11-05T04:45:00.868501067Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 5 04:45:00.868689 containerd[1546]: time="2025-11-05T04:45:00.868667279Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 5 04:45:00.868712 containerd[1546]: time="2025-11-05T04:45:00.868700872Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 5 04:45:00.868732 containerd[1546]: time="2025-11-05T04:45:00.868717873Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 5 04:45:00.868821 containerd[1546]: time="2025-11-05T04:45:00.868758980Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 5 04:45:00.868821 containerd[1546]: time="2025-11-05T04:45:00.868774349Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 5 04:45:00.868821 containerd[1546]: time="2025-11-05T04:45:00.868797433Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 5 04:45:00.868821 containerd[1546]: time="2025-11-05T04:45:00.868811499Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 5 04:45:00.868897 containerd[1546]: time="2025-11-05T04:45:00.868826196Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 5 04:45:00.868897 containerd[1546]: time="2025-11-05T04:45:00.868839552Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 5 04:45:00.868897 containerd[1546]: time="2025-11-05T04:45:00.868853638Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 5 04:45:00.868897 containerd[1546]: time="2025-11-05T04:45:00.868864418Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 5 04:45:00.868897 containerd[1546]: time="2025-11-05T04:45:00.868890207Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 5 04:45:00.868982 containerd[1546]: time="2025-11-05T04:45:00.868946051Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 5 04:45:00.868982 containerd[1546]: time="2025-11-05T04:45:00.868973513Z" level=info msg="Start snapshots syncer" Nov 5 04:45:00.869076 containerd[1546]: time="2025-11-05T04:45:00.869031491Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 5 04:45:00.869689 containerd[1546]: time="2025-11-05T04:45:00.869555223Z" 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 5 04:45:00.869689 containerd[1546]: time="2025-11-05T04:45:00.869641856Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870483223Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870657711Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870678630Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870689731Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870703416Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870714637Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870730026Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870741488Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870751246Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 5 04:45:00.870800 containerd[1546]: time="2025-11-05T04:45:00.870762727Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 5 04:45:00.871046 containerd[1546]: time="2025-11-05T04:45:00.871029528Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 04:45:00.871176 containerd[1546]: time="2025-11-05T04:45:00.871160002Z" 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 5 04:45:00.871243 containerd[1546]: time="2025-11-05T04:45:00.871229192Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 04:45:00.871294 containerd[1546]: time="2025-11-05T04:45:00.871281650Z" 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 5 04:45:00.871369 containerd[1546]: time="2025-11-05T04:45:00.871354818Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 5 04:45:00.871424 containerd[1546]: time="2025-11-05T04:45:00.871412706Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 5 04:45:00.871478 containerd[1546]: time="2025-11-05T04:45:00.871466848Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 5 04:45:00.871538 containerd[1546]: time="2025-11-05T04:45:00.871527852Z" level=info msg="runtime interface created" Nov 5 04:45:00.871598 containerd[1546]: time="2025-11-05T04:45:00.871586232Z" level=info msg="created NRI interface" Nov 5 04:45:00.871646 containerd[1546]: time="2025-11-05T04:45:00.871635133Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 5 04:45:00.871714 containerd[1546]: time="2025-11-05T04:45:00.871686650Z" level=info msg="Connect containerd service" Nov 5 04:45:00.871833 containerd[1546]: time="2025-11-05T04:45:00.871816924Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 5 04:45:00.873005 containerd[1546]: time="2025-11-05T04:45:00.872982720Z" 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 5 04:45:00.873078 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 04:45:00.873550 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 5 04:45:00.878822 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 5 04:45:00.903418 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 5 04:45:00.907389 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 5 04:45:00.910818 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 5 04:45:00.912989 systemd[1]: Reached target getty.target - Login Prompts. Nov 5 04:45:01.030225 containerd[1546]: time="2025-11-05T04:45:01.030150429Z" level=info msg="Start subscribing containerd event" Nov 5 04:45:01.030372 containerd[1546]: time="2025-11-05T04:45:01.030225470Z" level=info msg="Start recovering state" Nov 5 04:45:01.030436 containerd[1546]: time="2025-11-05T04:45:01.030403845Z" level=info msg="Start event monitor" Nov 5 04:45:01.030685 containerd[1546]: time="2025-11-05T04:45:01.030657240Z" level=info msg="Start cni network conf syncer for default" Nov 5 04:45:01.030685 containerd[1546]: time="2025-11-05T04:45:01.030683168Z" level=info msg="Start streaming server" Nov 5 04:45:01.030792 containerd[1546]: time="2025-11-05T04:45:01.030700731Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 5 04:45:01.030792 containerd[1546]: time="2025-11-05T04:45:01.030721250Z" level=info msg="runtime interface starting up..." Nov 5 04:45:01.030792 containerd[1546]: time="2025-11-05T04:45:01.030728303Z" level=info msg="starting plugins..." Nov 5 04:45:01.030792 containerd[1546]: time="2025-11-05T04:45:01.030751416Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 5 04:45:01.031086 containerd[1546]: time="2025-11-05T04:45:01.031027975Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 5 04:45:01.031217 containerd[1546]: time="2025-11-05T04:45:01.031184128Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 5 04:45:01.031363 containerd[1546]: time="2025-11-05T04:45:01.031336383Z" level=info msg="containerd successfully booted in 0.214804s" Nov 5 04:45:01.031527 systemd[1]: Started containerd.service - containerd container runtime. Nov 5 04:45:01.035365 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 5 04:45:01.064984 (dockerd)[1648]: 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 5 04:45:01.606772 dockerd[1648]: time="2025-11-05T04:45:01.606680924Z" level=info msg="Starting up" Nov 5 04:45:01.607469 dockerd[1648]: time="2025-11-05T04:45:01.607440749Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 5 04:45:01.622624 dockerd[1648]: time="2025-11-05T04:45:01.622533608Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 5 04:45:01.677750 dockerd[1648]: time="2025-11-05T04:45:01.677690707Z" level=info msg="Loading containers: start." Nov 5 04:45:01.691604 kernel: Initializing XFRM netlink socket Nov 5 04:45:02.077803 systemd-networkd[1453]: docker0: Link UP Nov 5 04:45:02.082717 dockerd[1648]: time="2025-11-05T04:45:02.082670254Z" level=info msg="Loading containers: done." Nov 5 04:45:02.102482 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2565626885-merged.mount: Deactivated successfully. Nov 5 04:45:02.104149 dockerd[1648]: time="2025-11-05T04:45:02.104087832Z" 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 5 04:45:02.104226 dockerd[1648]: time="2025-11-05T04:45:02.104214910Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 5 04:45:02.104341 dockerd[1648]: time="2025-11-05T04:45:02.104323534Z" level=info msg="Initializing buildkit" Nov 5 04:45:02.135914 dockerd[1648]: time="2025-11-05T04:45:02.135862880Z" level=info msg="Completed buildkit initialization" Nov 5 04:45:02.140360 dockerd[1648]: time="2025-11-05T04:45:02.140311902Z" level=info msg="Daemon has completed initialization" Nov 5 04:45:02.140454 dockerd[1648]: time="2025-11-05T04:45:02.140390880Z" level=info msg="API listen on /run/docker.sock" Nov 5 04:45:02.140809 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 5 04:45:02.142585 etcd-wrapper[1606]: Error response from daemon: No such container: etcd-member Nov 5 04:45:02.167701 etcd-wrapper[1847]: Error response from daemon: No such container: etcd-member Nov 5 04:45:02.214130 etcd-wrapper[1867]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally Nov 5 04:45:03.357682 etcd-wrapper[1867]: v3.5.22: Pulling from coreos/etcd Nov 5 04:45:03.625712 etcd-wrapper[1867]: f5083dc2076d: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 2ae710cd8bfe: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: d462aa345367: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 0f8b424aa0b9: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: d557676654e5: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: c8022d07192e: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: d858cbc252ad: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 1069fc2daed1: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: b40161cd83fc: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 5318d93a3a65: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 307c1adadb60: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 60a82e1b19c6: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 6f77055ebb6e: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 67f98bbc9874: Pulling fs layer Nov 5 04:45:03.625712 etcd-wrapper[1867]: 0f8b424aa0b9: Waiting Nov 5 04:45:03.625712 etcd-wrapper[1867]: d557676654e5: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: c8022d07192e: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: d858cbc252ad: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: b40161cd83fc: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: 1069fc2daed1: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: 5318d93a3a65: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: 307c1adadb60: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: 6f77055ebb6e: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: 67f98bbc9874: Waiting Nov 5 04:45:03.626139 etcd-wrapper[1867]: 60a82e1b19c6: Waiting Nov 5 04:45:03.849745 etcd-wrapper[1867]: 2ae710cd8bfe: Verifying Checksum Nov 5 04:45:03.849745 etcd-wrapper[1867]: 2ae710cd8bfe: Download complete Nov 5 04:45:03.859231 etcd-wrapper[1867]: d462aa345367: Verifying Checksum Nov 5 04:45:03.859352 etcd-wrapper[1867]: d462aa345367: Download complete Nov 5 04:45:03.883493 etcd-wrapper[1867]: f5083dc2076d: Download complete Nov 5 04:45:03.933117 etcd-wrapper[1867]: f5083dc2076d: Pull complete Nov 5 04:45:03.952997 systemd[1]: var-lib-docker-overlay2-92aee163999782be309cf7d69e61dd2f0722f3a687f0f28154d8cb0e5585eb8e-merged.mount: Deactivated successfully. Nov 5 04:45:03.975147 etcd-wrapper[1867]: 2ae710cd8bfe: Pull complete Nov 5 04:45:04.070726 etcd-wrapper[1867]: 0f8b424aa0b9: Verifying Checksum Nov 5 04:45:04.070726 etcd-wrapper[1867]: 0f8b424aa0b9: Download complete Nov 5 04:45:04.100269 etcd-wrapper[1867]: c8022d07192e: Verifying Checksum Nov 5 04:45:04.100269 etcd-wrapper[1867]: c8022d07192e: Download complete Nov 5 04:45:04.125791 etcd-wrapper[1867]: d557676654e5: Verifying Checksum Nov 5 04:45:04.125791 etcd-wrapper[1867]: d557676654e5: Download complete Nov 5 04:45:04.298823 etcd-wrapper[1867]: d858cbc252ad: Verifying Checksum Nov 5 04:45:04.298823 etcd-wrapper[1867]: d858cbc252ad: Download complete Nov 5 04:45:04.345597 etcd-wrapper[1867]: b40161cd83fc: Download complete Nov 5 04:45:04.401093 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 5 04:45:04.405084 systemd[1]: Started sshd@0-10.0.0.29:22-10.0.0.1:42192.service - OpenSSH per-connection server daemon (10.0.0.1:42192). Nov 5 04:45:04.416091 etcd-wrapper[1867]: 1069fc2daed1: Verifying Checksum Nov 5 04:45:04.416091 etcd-wrapper[1867]: 1069fc2daed1: Download complete Nov 5 04:45:04.476659 systemd[1]: var-lib-docker-overlay2-b44f97c4d310238e22cc0d5ec743054ee8ee35fa175a8a2fb6885726de6e35d9-merged.mount: Deactivated successfully. Nov 5 04:45:04.488137 sshd[1897]: Accepted publickey for core from 10.0.0.1 port 42192 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:04.491904 sshd-session[1897]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:04.499338 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 5 04:45:04.502319 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 5 04:45:04.511317 systemd-logind[1524]: New session 1 of user core. Nov 5 04:45:04.538277 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 5 04:45:04.544441 etcd-wrapper[1867]: 5318d93a3a65: Download complete Nov 5 04:45:04.544427 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 5 04:45:04.546720 etcd-wrapper[1867]: d462aa345367: Pull complete Nov 5 04:45:04.559135 (systemd)[1902]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 04:45:04.561664 systemd-logind[1524]: New session c1 of user core. Nov 5 04:45:04.563718 etcd-wrapper[1867]: 307c1adadb60: Verifying Checksum Nov 5 04:45:04.563718 etcd-wrapper[1867]: 307c1adadb60: Download complete Nov 5 04:45:04.572364 etcd-wrapper[1867]: 0f8b424aa0b9: Pull complete Nov 5 04:45:04.597319 etcd-wrapper[1867]: d557676654e5: Pull complete Nov 5 04:45:04.626998 etcd-wrapper[1867]: c8022d07192e: Pull complete Nov 5 04:45:04.653174 etcd-wrapper[1867]: d858cbc252ad: Pull complete Nov 5 04:45:04.680365 etcd-wrapper[1867]: 1069fc2daed1: Pull complete Nov 5 04:45:04.707263 etcd-wrapper[1867]: b40161cd83fc: Pull complete Nov 5 04:45:04.719201 systemd[1902]: Queued start job for default target default.target. Nov 5 04:45:04.731547 etcd-wrapper[1867]: 5318d93a3a65: Pull complete Nov 5 04:45:04.733975 systemd[1902]: Created slice app.slice - User Application Slice. Nov 5 04:45:04.734000 systemd[1902]: Reached target paths.target - Paths. Nov 5 04:45:04.734191 systemd[1902]: Reached target timers.target - Timers. Nov 5 04:45:04.735887 systemd[1902]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 04:45:04.752355 systemd[1902]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 04:45:04.752488 systemd[1902]: Reached target sockets.target - Sockets. Nov 5 04:45:04.752528 systemd[1902]: Reached target basic.target - Basic System. Nov 5 04:45:04.752616 systemd[1902]: Reached target default.target - Main User Target. Nov 5 04:45:04.752652 systemd[1902]: Startup finished in 181ms. Nov 5 04:45:04.753237 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 5 04:45:04.764073 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 5 04:45:04.771235 etcd-wrapper[1867]: 307c1adadb60: Pull complete Nov 5 04:45:04.823780 systemd[1]: Started sshd@1-10.0.0.29:22-10.0.0.1:42196.service - OpenSSH per-connection server daemon (10.0.0.1:42196). Nov 5 04:45:04.849775 etcd-wrapper[1867]: 60a82e1b19c6: Verifying Checksum Nov 5 04:45:04.850271 etcd-wrapper[1867]: 60a82e1b19c6: Download complete Nov 5 04:45:04.880304 etcd-wrapper[1867]: 6f77055ebb6e: Verifying Checksum Nov 5 04:45:04.880304 etcd-wrapper[1867]: 6f77055ebb6e: Download complete Nov 5 04:45:04.886312 etcd-wrapper[1867]: 67f98bbc9874: Verifying Checksum Nov 5 04:45:04.886312 etcd-wrapper[1867]: 67f98bbc9874: Download complete Nov 5 04:45:04.886649 sshd[1952]: Accepted publickey for core from 10.0.0.1 port 42196 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:04.888250 sshd-session[1952]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:04.892877 systemd-logind[1524]: New session 2 of user core. Nov 5 04:45:04.905703 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 5 04:45:04.921421 sshd[1962]: Connection closed by 10.0.0.1 port 42196 Nov 5 04:45:04.921805 sshd-session[1952]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:04.934080 systemd[1]: sshd@1-10.0.0.29:22-10.0.0.1:42196.service: Deactivated successfully. Nov 5 04:45:04.936217 systemd[1]: session-2.scope: Deactivated successfully. Nov 5 04:45:04.937206 systemd-logind[1524]: Session 2 logged out. Waiting for processes to exit. Nov 5 04:45:04.940867 systemd-logind[1524]: Removed session 2. Nov 5 04:45:04.942851 systemd[1]: Started sshd@2-10.0.0.29:22-10.0.0.1:42210.service - OpenSSH per-connection server daemon (10.0.0.1:42210). Nov 5 04:45:04.989824 sshd[1968]: Accepted publickey for core from 10.0.0.1 port 42210 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:04.991697 sshd-session[1968]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:04.997814 systemd-logind[1524]: New session 3 of user core. Nov 5 04:45:05.004771 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 5 04:45:05.022265 sshd[1971]: Connection closed by 10.0.0.1 port 42210 Nov 5 04:45:05.022515 sshd-session[1968]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:05.027030 systemd[1]: sshd@2-10.0.0.29:22-10.0.0.1:42210.service: Deactivated successfully. Nov 5 04:45:05.029543 systemd[1]: session-3.scope: Deactivated successfully. Nov 5 04:45:05.031674 systemd-logind[1524]: Session 3 logged out. Waiting for processes to exit. Nov 5 04:45:05.033512 systemd-logind[1524]: Removed session 3. Nov 5 04:45:05.042708 systemd[1]: var-lib-docker-overlay2-ae257c83196f99ddbf2b76d306311274218b6fda8690f63a5e08a999906b5e9a-merged.mount: Deactivated successfully. Nov 5 04:45:05.187069 etcd-wrapper[1867]: 60a82e1b19c6: Pull complete Nov 5 04:45:05.293294 systemd[1]: var-lib-docker-overlay2-9bb499849ee9a399dbd977b8987899ee7df43cb206c908f93be79d400dd014ed-merged.mount: Deactivated successfully. Nov 5 04:45:05.368444 etcd-wrapper[1867]: 6f77055ebb6e: Pull complete Nov 5 04:45:05.628427 etcd-wrapper[1867]: 67f98bbc9874: Pull complete Nov 5 04:45:05.639085 etcd-wrapper[1867]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6 Nov 5 04:45:05.642179 etcd-wrapper[1867]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22 Nov 5 04:45:05.705792 containerd[1546]: time="2025-11-05T04:45:05.705707928Z" level=info msg="connecting to shim a2d8753bf000001141cadba2acc83e6fd34f56178c787373209b308f0ce5c95c" address="unix:///run/containerd/s/19eb9895b36c0c66e0107e9ed70ff4d4f3c4dbb6fd631f1199a071ef74c0978f" namespace=moby protocol=ttrpc version=3 Nov 5 04:45:05.741735 systemd[1]: Started docker-a2d8753bf000001141cadba2acc83e6fd34f56178c787373209b308f0ce5c95c.scope - libcontainer container a2d8753bf000001141cadba2acc83e6fd34f56178c787373209b308f0ce5c95c. Nov 5 04:45:05.760864 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.822679Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.822880Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"3d35a656c9cc4407b119f6384ebcdcf9"} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.822904Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.822911Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.822917Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.822921Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.822958Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 5 04:45:05.823699 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.823011Z","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.29:2379"]} Nov 5 04:45:05.824144 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.823085Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 5 04:45:05.824144 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.823099Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Nov 5 04:45:05.824144 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.823834Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Nov 5 04:45:05.824373 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.824031Z","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":"3d35a656c9cc4407b119f6384ebcdcf9","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.29:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"3d35a656c9cc4407b119f6384ebcdcf9=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 5 04:45:05.827400 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.827231Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.439564ms"} Nov 5 04:45:05.831528 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.831357Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Nov 5 04:45:05.831528 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.831413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Nov 5 04:45:05.831528 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.831447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Nov 5 04:45:05.831528 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.831459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Nov 5 04:45:05.831528 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.831472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Nov 5 04:45:05.831687 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.831512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 5 04:45:05.835954 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:45:05.835790Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Nov 5 04:45:05.838315 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.838135Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Nov 5 04:45:05.838377 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.838173Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Nov 5 04:45:05.839205 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.839046Z","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 5 04:45:05.840080 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.839898Z","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 5 04:45:05.840124 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.839987Z","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 5 04:45:05.840269 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.840115Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 5 04:45:05.840521 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.840188Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Nov 5 04:45:05.840521 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.840347Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Nov 5 04:45:05.840521 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.840355Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Nov 5 04:45:05.841093 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.840935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 5 04:45:05.841157 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.841022Z","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 5 04:45:05.843098 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.842888Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Nov 5 04:45:05.843098 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.842957Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Nov 5 04:45:05.843098 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:05.842989Z","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.29:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Nov 5 04:45:05.948060 systemd[1]: var-lib-docker-overlay2-f58b32b17fc9e0afaa5f8093d89097d65033e156d0c42c758ab711c0975b9339-merged.mount: Deactivated successfully. Nov 5 04:45:06.032719 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Nov 5 04:45:06.032719 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Nov 5 04:45:06.032719 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Nov 5 04:45:06.032719 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Nov 5 04:45:06.032719 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Nov 5 04:45:06.032719 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Nov 5 04:45:06.032980 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.032638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Nov 5 04:45:06.033981 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.033721Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:3d35a656c9cc4407b119f6384ebcdcf9 ClientURLs:[http://10.0.0.29:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Nov 5 04:45:06.033981 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.033744Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Nov 5 04:45:06.034063 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.033907Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Nov 5 04:45:06.034230 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.033730Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Nov 5 04:45:06.034546 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.034175Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Nov 5 04:45:06.035475 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.034539Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Nov 5 04:45:06.035475 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.034663Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Nov 5 04:45:06.035475 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.034719Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} Nov 5 04:45:06.035475 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.034673Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 5 04:45:06.034730 systemd[1]: Started etcd-member.service - etcd (System Application Container). Nov 5 04:45:06.035910 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:45:06.035207Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Nov 5 04:45:06.035341 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 5 04:45:06.036028 systemd[1]: Startup finished in 3.462s (kernel) + 4.216s (initrd) + 9.347s (userspace) = 17.026s. Nov 5 04:45:15.046370 systemd[1]: Started sshd@3-10.0.0.29:22-10.0.0.1:51092.service - OpenSSH per-connection server daemon (10.0.0.1:51092). Nov 5 04:45:15.099046 sshd[2041]: Accepted publickey for core from 10.0.0.1 port 51092 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.100264 sshd-session[2041]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.104461 systemd-logind[1524]: New session 4 of user core. Nov 5 04:45:15.117753 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 5 04:45:15.129651 sshd[2044]: Connection closed by 10.0.0.1 port 51092 Nov 5 04:45:15.129892 sshd-session[2041]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:15.137995 systemd[1]: sshd@3-10.0.0.29:22-10.0.0.1:51092.service: Deactivated successfully. Nov 5 04:45:15.139839 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 04:45:15.140523 systemd-logind[1524]: Session 4 logged out. Waiting for processes to exit. Nov 5 04:45:15.143311 systemd[1]: Started sshd@4-10.0.0.29:22-10.0.0.1:51100.service - OpenSSH per-connection server daemon (10.0.0.1:51100). Nov 5 04:45:15.143904 systemd-logind[1524]: Removed session 4. Nov 5 04:45:15.199672 sshd[2050]: Accepted publickey for core from 10.0.0.1 port 51100 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.200885 sshd-session[2050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.205048 systemd-logind[1524]: New session 5 of user core. Nov 5 04:45:15.223719 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 5 04:45:15.231735 sshd[2053]: Connection closed by 10.0.0.1 port 51100 Nov 5 04:45:15.232015 sshd-session[2050]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:15.243892 systemd[1]: sshd@4-10.0.0.29:22-10.0.0.1:51100.service: Deactivated successfully. Nov 5 04:45:15.245527 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 04:45:15.246277 systemd-logind[1524]: Session 5 logged out. Waiting for processes to exit. Nov 5 04:45:15.249082 systemd[1]: Started sshd@5-10.0.0.29:22-10.0.0.1:51108.service - OpenSSH per-connection server daemon (10.0.0.1:51108). Nov 5 04:45:15.249643 systemd-logind[1524]: Removed session 5. Nov 5 04:45:15.298073 sshd[2059]: Accepted publickey for core from 10.0.0.1 port 51108 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.299467 sshd-session[2059]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.303657 systemd-logind[1524]: New session 6 of user core. Nov 5 04:45:15.310689 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 5 04:45:15.323370 sshd[2062]: Connection closed by 10.0.0.1 port 51108 Nov 5 04:45:15.323756 sshd-session[2059]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:15.334140 systemd[1]: sshd@5-10.0.0.29:22-10.0.0.1:51108.service: Deactivated successfully. Nov 5 04:45:15.336035 systemd[1]: session-6.scope: Deactivated successfully. Nov 5 04:45:15.336825 systemd-logind[1524]: Session 6 logged out. Waiting for processes to exit. Nov 5 04:45:15.339429 systemd[1]: Started sshd@6-10.0.0.29:22-10.0.0.1:51116.service - OpenSSH per-connection server daemon (10.0.0.1:51116). Nov 5 04:45:15.340181 systemd-logind[1524]: Removed session 6. Nov 5 04:45:15.392785 sshd[2068]: Accepted publickey for core from 10.0.0.1 port 51116 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.394055 sshd-session[2068]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.398283 systemd-logind[1524]: New session 7 of user core. Nov 5 04:45:15.407703 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 5 04:45:15.427707 sudo[2073]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 5 04:45:15.428017 sudo[2073]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 04:45:15.445201 sudo[2073]: pam_unix(sudo:session): session closed for user root Nov 5 04:45:15.446929 sshd[2072]: Connection closed by 10.0.0.1 port 51116 Nov 5 04:45:15.447366 sshd-session[2068]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:15.463093 systemd[1]: sshd@6-10.0.0.29:22-10.0.0.1:51116.service: Deactivated successfully. Nov 5 04:45:15.464843 systemd[1]: session-7.scope: Deactivated successfully. Nov 5 04:45:15.465587 systemd-logind[1524]: Session 7 logged out. Waiting for processes to exit. Nov 5 04:45:15.468215 systemd[1]: Started sshd@7-10.0.0.29:22-10.0.0.1:51128.service - OpenSSH per-connection server daemon (10.0.0.1:51128). Nov 5 04:45:15.469003 systemd-logind[1524]: Removed session 7. Nov 5 04:45:15.526725 sshd[2079]: Accepted publickey for core from 10.0.0.1 port 51128 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.528014 sshd-session[2079]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.532629 systemd-logind[1524]: New session 8 of user core. Nov 5 04:45:15.542699 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 5 04:45:15.556321 sudo[2084]: 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 5 04:45:15.556658 sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 04:45:15.563435 sudo[2084]: pam_unix(sudo:session): session closed for user root Nov 5 04:45:15.571096 sudo[2083]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 5 04:45:15.571390 sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 04:45:15.581660 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 04:45:15.621711 augenrules[2106]: No rules Nov 5 04:45:15.623292 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 04:45:15.623606 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 04:45:15.624797 sudo[2083]: pam_unix(sudo:session): session closed for user root Nov 5 04:45:15.626612 sshd[2082]: Connection closed by 10.0.0.1 port 51128 Nov 5 04:45:15.626932 sshd-session[2079]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:15.639200 systemd[1]: sshd@7-10.0.0.29:22-10.0.0.1:51128.service: Deactivated successfully. Nov 5 04:45:15.641017 systemd[1]: session-8.scope: Deactivated successfully. Nov 5 04:45:15.641774 systemd-logind[1524]: Session 8 logged out. Waiting for processes to exit. Nov 5 04:45:15.644663 systemd[1]: Started sshd@8-10.0.0.29:22-10.0.0.1:51132.service - OpenSSH per-connection server daemon (10.0.0.1:51132). Nov 5 04:45:15.645388 systemd-logind[1524]: Removed session 8. Nov 5 04:45:15.692461 sshd[2115]: Accepted publickey for core from 10.0.0.1 port 51132 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.693756 sshd-session[2115]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.697988 systemd-logind[1524]: New session 9 of user core. Nov 5 04:45:15.708742 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 5 04:45:15.718154 sshd[2118]: Connection closed by 10.0.0.1 port 51132 Nov 5 04:45:15.718469 sshd-session[2115]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:15.731118 systemd[1]: sshd@8-10.0.0.29:22-10.0.0.1:51132.service: Deactivated successfully. Nov 5 04:45:15.732994 systemd[1]: session-9.scope: Deactivated successfully. Nov 5 04:45:15.733731 systemd-logind[1524]: Session 9 logged out. Waiting for processes to exit. Nov 5 04:45:15.736358 systemd[1]: Started sshd@9-10.0.0.29:22-10.0.0.1:51144.service - OpenSSH per-connection server daemon (10.0.0.1:51144). Nov 5 04:45:15.736908 systemd-logind[1524]: Removed session 9. Nov 5 04:45:15.791277 sshd[2126]: Accepted publickey for core from 10.0.0.1 port 51144 ssh2: RSA SHA256:XiGyK5fqllnBQWxDYED3xW8VH4cMJfuo/fZHqIgMrko Nov 5 04:45:15.792536 sshd-session[2126]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 04:45:15.796681 systemd-logind[1524]: New session 10 of user core. Nov 5 04:45:15.806693 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 5 04:45:16.123906 sshd[2129]: Connection closed by 10.0.0.1 port 51144 Nov 5 04:45:16.124139 sshd-session[2126]: pam_unix(sshd:session): session closed for user core Nov 5 04:45:16.129410 systemd[1]: sshd@9-10.0.0.29:22-10.0.0.1:51144.service: Deactivated successfully. Nov 5 04:45:16.131432 systemd[1]: session-10.scope: Deactivated successfully. Nov 5 04:45:16.132239 systemd-logind[1524]: Session 10 logged out. Waiting for processes to exit. Nov 5 04:45:16.133525 systemd-logind[1524]: Removed session 10. Nov 5 04:45:46.031837 update_engine[1530]: I20251105 04:45:46.031656 1530 update_attempter.cc:509] Updating boot flags... Nov 5 04:46:40.371742 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.370522Z","caller":"traceutil/trace.go:171","msg":"trace[881398586] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"313.748675ms","start":"2025-11-05T04:46:40.056745Z","end":"2025-11-05T04:46:40.370493Z","steps":["trace[881398586] 'process raft request' (duration: 280.938417ms)","trace[881398586] 'compare' (duration: 32.588801ms)"],"step_count":2} Nov 5 04:46:40.371742 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.370689Z","caller":"traceutil/trace.go:171","msg":"trace[1792735996] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:482; }","duration":"232.167197ms","start":"2025-11-05T04:46:40.138492Z","end":"2025-11-05T04:46:40.370659Z","steps":["trace[1792735996] 'read index received' (duration: 199.203484ms)","trace[1792735996] 'applied index is now lower than readState.Index' (duration: 32.96205ms)"],"step_count":2} Nov 5 04:46:40.371742 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.370774Z","caller":"traceutil/trace.go:171","msg":"trace[2136924822] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"308.29169ms","start":"2025-11-05T04:46:40.062477Z","end":"2025-11-05T04:46:40.370769Z","steps":["trace[2136924822] 'process raft request' (duration: 307.986071ms)"],"step_count":1} Nov 5 04:46:40.371742 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:40.370898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.401288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" limit:1 ","response":"range_response_count:1 size:4673"} Nov 5 04:46:40.372966 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.370933Z","caller":"traceutil/trace.go:171","msg":"trace[1856727320] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:468; }","duration":"232.446195ms","start":"2025-11-05T04:46:40.138478Z","end":"2025-11-05T04:46:40.370924Z","steps":["trace[1856727320] 'agreement among raft nodes before linearized reading' (duration: 232.346594ms)"],"step_count":1} Nov 5 04:46:40.372966 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:40.370907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.946962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/10.0.0.45\" limit:1 ","response":"range_response_count:0 size:5"} Nov 5 04:46:40.372966 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.370984Z","caller":"traceutil/trace.go:171","msg":"trace[1536154965] range","detail":"{range_begin:/registry/leases/kube-node-lease/10.0.0.45; range_end:; response_count:0; response_revision:468; }","duration":"137.049159ms","start":"2025-11-05T04:46:40.233930Z","end":"2025-11-05T04:46:40.370979Z","steps":["trace[1536154965] 'agreement among raft nodes before linearized reading' (duration: 136.896815ms)"],"step_count":1} Nov 5 04:46:40.373194 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:40.371055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:46:40.056698Z","time spent":"313.960363ms","remote":"10.0.0.33:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178147,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 5 04:46:40.373194 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:40.371133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:46:40.062456Z","time spent":"308.336126ms","remote":"10.0.0.33:44054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":643,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Nov 5 04:46:40.602043 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.601539Z","caller":"traceutil/trace.go:171","msg":"trace[1384490427] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"189.437729ms","start":"2025-11-05T04:46:40.412076Z","end":"2025-11-05T04:46:40.601514Z","steps":["trace[1384490427] 'process raft request' (duration: 179.856889ms)"],"step_count":1} Nov 5 04:46:40.649110 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.648647Z","caller":"traceutil/trace.go:171","msg":"trace[706683603] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"232.707909ms","start":"2025-11-05T04:46:40.415911Z","end":"2025-11-05T04:46:40.648619Z","steps":["trace[706683603] 'process raft request' (duration: 232.489899ms)"],"step_count":1} Nov 5 04:46:40.649890 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.649008Z","caller":"traceutil/trace.go:171","msg":"trace[305418611] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:484; }","duration":"109.317668ms","start":"2025-11-05T04:46:40.539672Z","end":"2025-11-05T04:46:40.648989Z","steps":["trace[305418611] 'read index received' (duration: 52.289038ms)","trace[305418611] 'applied index is now lower than readState.Index' (duration: 57.028099ms)"],"step_count":2} Nov 5 04:46:40.649890 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.649055Z","caller":"traceutil/trace.go:171","msg":"trace[115724289] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"184.166323ms","start":"2025-11-05T04:46:40.464864Z","end":"2025-11-05T04:46:40.649030Z","steps":["trace[115724289] 'process raft request' (duration: 183.829745ms)"],"step_count":1} Nov 5 04:46:40.649890 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:40.649171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.488166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" limit:1 ","response":"range_response_count:1 size:13226"} Nov 5 04:46:40.649890 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:40.649197Z","caller":"traceutil/trace.go:171","msg":"trace[1546300806] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:471; }","duration":"109.532021ms","start":"2025-11-05T04:46:40.539657Z","end":"2025-11-05T04:46:40.649189Z","steps":["trace[1546300806] 'agreement among raft nodes before linearized reading' (duration: 109.450514ms)"],"step_count":1} Nov 5 04:46:41.190902 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.190512Z","caller":"traceutil/trace.go:171","msg":"trace[224122356] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"168.077336ms","start":"2025-11-05T04:46:41.022417Z","end":"2025-11-05T04:46:41.190495Z","steps":["trace[224122356] 'process raft request' (duration: 147.609439ms)","trace[224122356] 'compare' (duration: 20.373585ms)"],"step_count":2} Nov 5 04:46:41.342824 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.342231Z","caller":"traceutil/trace.go:171","msg":"trace[1087364083] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:515; }","duration":"130.438048ms","start":"2025-11-05T04:46:41.211771Z","end":"2025-11-05T04:46:41.342209Z","steps":["trace[1087364083] 'read index received' (duration: 5.306347ms)","trace[1087364083] 'applied index is now lower than readState.Index' (duration: 125.13081ms)"],"step_count":2} Nov 5 04:46:41.342824 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.342279Z","caller":"traceutil/trace.go:171","msg":"trace[1971504369] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"144.35325ms","start":"2025-11-05T04:46:41.197908Z","end":"2025-11-05T04:46:41.342262Z","steps":["trace[1971504369] 'process raft request' (duration: 95.203901ms)","trace[1971504369] 'compare' (duration: 49.000772ms)"],"step_count":2} Nov 5 04:46:41.342824 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:41.342359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.54833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" limit:1 ","response":"range_response_count:1 size:177"} Nov 5 04:46:41.342824 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:41.342389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.566045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" limit:1 ","response":"range_response_count:1 size:185"} Nov 5 04:46:41.342824 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.342417Z","caller":"traceutil/trace.go:171","msg":"trace[709956526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:500; }","duration":"130.609738ms","start":"2025-11-05T04:46:41.211801Z","end":"2025-11-05T04:46:41.342411Z","steps":["trace[709956526] 'agreement among raft nodes before linearized reading' (duration: 130.542279ms)"],"step_count":1} Nov 5 04:46:41.343128 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.342418Z","caller":"traceutil/trace.go:171","msg":"trace[330016126] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:500; }","duration":"130.63678ms","start":"2025-11-05T04:46:41.211767Z","end":"2025-11-05T04:46:41.342404Z","steps":["trace[330016126] 'agreement among raft nodes before linearized reading' (duration: 130.515677ms)"],"step_count":1} Nov 5 04:46:41.613922 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.613467Z","caller":"traceutil/trace.go:171","msg":"trace[491141648] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"178.506746ms","start":"2025-11-05T04:46:41.434941Z","end":"2025-11-05T04:46:41.613448Z","steps":["trace[491141648] 'read index received' (duration: 151.918917ms)","trace[491141648] 'applied index is now lower than readState.Index' (duration: 26.587308ms)"],"step_count":2} Nov 5 04:46:41.613922 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.613513Z","caller":"traceutil/trace.go:171","msg":"trace[1577398928] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"179.324581ms","start":"2025-11-05T04:46:41.434167Z","end":"2025-11-05T04:46:41.613492Z","steps":["trace[1577398928] 'process raft request' (duration: 152.697856ms)","trace[1577398928] 'compare' (duration: 26.502346ms)"],"step_count":2} Nov 5 04:46:41.613922 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:41.613609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.655832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-t5f4h.187502dde4f8ab35\" limit:1 ","response":"range_response_count:1 size:788"} Nov 5 04:46:41.613922 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.613630Z","caller":"traceutil/trace.go:171","msg":"trace[1384959205] range","detail":"{range_begin:/registry/events/kube-system/cilium-t5f4h.187502dde4f8ab35; range_end:; response_count:1; response_revision:503; }","duration":"178.696301ms","start":"2025-11-05T04:46:41.434929Z","end":"2025-11-05T04:46:41.613625Z","steps":["trace[1384959205] 'agreement among raft nodes before linearized reading' (duration: 178.619483ms)"],"step_count":1} Nov 5 04:46:41.826206 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.825836Z","caller":"traceutil/trace.go:171","msg":"trace[410829602] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"144.810158ms","start":"2025-11-05T04:46:41.681015Z","end":"2025-11-05T04:46:41.825825Z","steps":["trace[410829602] 'process raft request' (duration: 144.790871ms)"],"step_count":1} Nov 5 04:46:41.826206 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.825890Z","caller":"traceutil/trace.go:171","msg":"trace[306571523] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"203.142878ms","start":"2025-11-05T04:46:41.622719Z","end":"2025-11-05T04:46:41.825862Z","steps":["trace[306571523] 'process raft request' (duration: 203.056612ms)"],"step_count":1} Nov 5 04:46:41.826206 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.825839Z","caller":"traceutil/trace.go:171","msg":"trace[1785044970] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"208.977373ms","start":"2025-11-05T04:46:41.616843Z","end":"2025-11-05T04:46:41.825820Z","steps":["trace[1785044970] 'process raft request' (duration: 120.363501ms)","trace[1785044970] 'compare' (duration: 88.472178ms)"],"step_count":2} Nov 5 04:46:41.909650 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:41.909295Z","caller":"traceutil/trace.go:171","msg":"trace[1593680029] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"119.639497ms","start":"2025-11-05T04:46:41.789641Z","end":"2025-11-05T04:46:41.909281Z","steps":["trace[1593680029] 'process raft request' (duration: 119.55787ms)"],"step_count":1} Nov 5 04:46:42.025440 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:42.024829Z","caller":"traceutil/trace.go:171","msg":"trace[780581213] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"196.791939ms","start":"2025-11-05T04:46:41.828013Z","end":"2025-11-05T04:46:42.024805Z","steps":["trace[780581213] 'process raft request' (duration: 142.384188ms)","trace[780581213] 'compare' (duration: 54.263233ms)"],"step_count":2} Nov 5 04:46:42.025440 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:42.024812Z","caller":"traceutil/trace.go:171","msg":"trace[1330637220] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"196.038158ms","start":"2025-11-05T04:46:41.828745Z","end":"2025-11-05T04:46:42.024783Z","steps":["trace[1330637220] 'read index received' (duration: 80.460344ms)","trace[1330637220] 'applied index is now lower than readState.Index' (duration: 115.576923ms)"],"step_count":2} Nov 5 04:46:42.025440 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:42.024993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.231029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:493"} Nov 5 04:46:42.025440 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:42.025044Z","caller":"traceutil/trace.go:171","msg":"trace[1573112473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:509; }","duration":"196.293158ms","start":"2025-11-05T04:46:41.828740Z","end":"2025-11-05T04:46:42.025033Z","steps":["trace[1573112473] 'agreement among raft nodes before linearized reading' (duration: 196.135034ms)"],"step_count":1} Nov 5 04:46:42.025797 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:42.025162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.943607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} Nov 5 04:46:42.025797 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:42.025291Z","caller":"traceutil/trace.go:171","msg":"trace[800530111] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:509; }","duration":"187.019804ms","start":"2025-11-05T04:46:41.838190Z","end":"2025-11-05T04:46:42.025210Z","steps":["trace[800530111] 'agreement among raft nodes before linearized reading' (duration: 186.921114ms)"],"step_count":1} Nov 5 04:46:42.025797 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:42.025392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.97906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} Nov 5 04:46:42.025797 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:42.025411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.177758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} Nov 5 04:46:42.025913 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:42.025416Z","caller":"traceutil/trace.go:171","msg":"trace[2086565675] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:509; }","duration":"186.006644ms","start":"2025-11-05T04:46:41.839402Z","end":"2025-11-05T04:46:42.025409Z","steps":["trace[2086565675] 'agreement among raft nodes before linearized reading' (duration: 185.955234ms)"],"step_count":1} Nov 5 04:46:42.025913 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:42.025434Z","caller":"traceutil/trace.go:171","msg":"trace[454068598] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:509; }","duration":"187.201664ms","start":"2025-11-05T04:46:41.838225Z","end":"2025-11-05T04:46:42.025427Z","steps":["trace[454068598] 'agreement among raft nodes before linearized reading' (duration: 187.171025ms)"],"step_count":1} Nov 5 04:46:45.576291 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:45.575820Z","caller":"traceutil/trace.go:171","msg":"trace[1761881901] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"152.695482ms","start":"2025-11-05T04:46:45.423087Z","end":"2025-11-05T04:46:45.575783Z","steps":["trace[1761881901] 'process raft request' (duration: 82.608386ms)","trace[1761881901] 'compare' (duration: 69.959261ms)"],"step_count":2} Nov 5 04:46:46.052595 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:46.052177Z","caller":"traceutil/trace.go:171","msg":"trace[374354267] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"135.493093ms","start":"2025-11-05T04:46:45.916669Z","end":"2025-11-05T04:46:46.052162Z","steps":["trace[374354267] 'process raft request' (duration: 135.393773ms)"],"step_count":1} Nov 5 04:46:47.696718 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:47.696309Z","caller":"traceutil/trace.go:171","msg":"trace[208134274] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"115.865912ms","start":"2025-11-05T04:46:47.580419Z","end":"2025-11-05T04:46:47.696285Z","steps":["trace[208134274] 'process raft request' (duration: 111.201907ms)"],"step_count":1} Nov 5 04:46:48.195478 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:48.195148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.854325ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Nov 5 04:46:48.195760 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:48.195304Z","caller":"traceutil/trace.go:171","msg":"trace[487241177] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"102.161207ms","start":"2025-11-05T04:46:48.093131Z","end":"2025-11-05T04:46:48.195292Z","steps":["trace[487241177] 'read index received' (duration: 56.56794ms)","trace[487241177] 'applied index is now lower than readState.Index' (duration: 45.592926ms)"],"step_count":2} Nov 5 04:46:48.195760 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:48.195395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.263742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" limit:1 ","response":"range_response_count:1 size:492"} Nov 5 04:46:48.195760 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:48.195412Z","caller":"traceutil/trace.go:171","msg":"trace[240094728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:559; }","duration":"102.287718ms","start":"2025-11-05T04:46:48.093119Z","end":"2025-11-05T04:46:48.195406Z","steps":["trace[240094728] 'agreement among raft nodes before linearized reading' (duration: 102.210651ms)"],"step_count":1} Nov 5 04:46:48.195760 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:48.195414Z","caller":"traceutil/trace.go:171","msg":"trace[1766466324] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"139.332598ms","start":"2025-11-05T04:46:48.056072Z","end":"2025-11-05T04:46:48.195405Z","steps":["trace[1766466324] 'process raft request' (duration: 139.152804ms)"],"step_count":1} Nov 5 04:46:48.603160 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:48.602744Z","caller":"traceutil/trace.go:171","msg":"trace[1436235528] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"147.682804ms","start":"2025-11-05T04:46:48.455044Z","end":"2025-11-05T04:46:48.602727Z","steps":["trace[1436235528] 'process raft request' (duration: 147.570208ms)"],"step_count":1} Nov 5 04:46:50.383657 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:50.383160Z","caller":"traceutil/trace.go:171","msg":"trace[1309499506] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"113.355707ms","start":"2025-11-05T04:46:50.269771Z","end":"2025-11-05T04:46:50.383126Z","steps":["trace[1309499506] 'read index received' (duration: 19.893786ms)","trace[1309499506] 'applied index is now lower than readState.Index' (duration: 93.461249ms)"],"step_count":2} Nov 5 04:46:50.383657 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:50.383257Z","caller":"traceutil/trace.go:171","msg":"trace[1653787604] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"140.785951ms","start":"2025-11-05T04:46:50.242457Z","end":"2025-11-05T04:46:50.383243Z","steps":["trace[1653787604] 'process raft request' (duration: 47.23529ms)","trace[1653787604] 'compare' (duration: 93.344246ms)"],"step_count":2} Nov 5 04:46:50.383657 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:50.383281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.499202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints\" limit:1 ","response":"range_response_count:0 size:5"} Nov 5 04:46:50.383657 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:50.383305Z","caller":"traceutil/trace.go:171","msg":"trace[1399141282] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints; range_end:; response_count:0; response_revision:570; }","duration":"113.539149ms","start":"2025-11-05T04:46:50.269760Z","end":"2025-11-05T04:46:50.383299Z","steps":["trace[1399141282] 'agreement among raft nodes before linearized reading' (duration: 113.486989ms)"],"step_count":1} Nov 5 04:46:50.383657 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:46:50.383339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.41002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies\" limit:1 ","response":"range_response_count:0 size:5"} Nov 5 04:46:50.384410 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:46:50.383358Z","caller":"traceutil/trace.go:171","msg":"trace[1486126507] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies; range_end:; response_count:0; response_revision:570; }","duration":"113.43076ms","start":"2025-11-05T04:46:50.269921Z","end":"2025-11-05T04:46:50.383352Z","steps":["trace[1486126507] 'agreement among raft nodes before linearized reading' (duration: 113.401434ms)"],"step_count":1} Nov 5 04:47:40.912468 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:40.912013Z","caller":"traceutil/trace.go:171","msg":"trace[1553504857] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"102.130967ms","start":"2025-11-05T04:47:40.809847Z","end":"2025-11-05T04:47:40.911978Z","steps":["trace[1553504857] 'read index received' (duration: 35.430584ms)","trace[1553504857] 'applied index is now lower than readState.Index' (duration: 66.699952ms)"],"step_count":2} Nov 5 04:47:40.912468 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:40.912041Z","caller":"traceutil/trace.go:171","msg":"trace[187621296] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"262.778832ms","start":"2025-11-05T04:47:40.649245Z","end":"2025-11-05T04:47:40.912024Z","steps":["trace[187621296] 'process raft request' (duration: 196.040919ms)","trace[187621296] 'compare' (duration: 66.618248ms)"],"step_count":2} Nov 5 04:47:40.912468 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:40.912098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.226728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-gxvgd.187502f009359da2\" limit:1 ","response":"range_response_count:1 size:788"} Nov 5 04:47:40.912468 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:40.912168Z","caller":"traceutil/trace.go:171","msg":"trace[1266950035] range","detail":"{range_begin:/registry/events/kube-system/cilium-gxvgd.187502f009359da2; range_end:; response_count:1; response_revision:903; }","duration":"102.313833ms","start":"2025-11-05T04:47:40.809841Z","end":"2025-11-05T04:47:40.912155Z","steps":["trace[1266950035] 'agreement among raft nodes before linearized reading' (duration: 102.143942ms)"],"step_count":1} Nov 5 04:47:40.912468 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:40.912223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.364768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gxvgd\" limit:1 ","response":"range_response_count:1 size:17533"} Nov 5 04:47:40.913371 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:40.912250Z","caller":"traceutil/trace.go:171","msg":"trace[1708974649] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gxvgd; range_end:; response_count:1; response_revision:903; }","duration":"102.392611ms","start":"2025-11-05T04:47:40.809850Z","end":"2025-11-05T04:47:40.912243Z","steps":["trace[1708974649] 'agreement among raft nodes before linearized reading' (duration: 102.319643ms)"],"step_count":1} Nov 5 04:47:41.267897 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:41.267383Z","caller":"traceutil/trace.go:171","msg":"trace[211310553] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"345.998745ms","start":"2025-11-05T04:47:40.921362Z","end":"2025-11-05T04:47:41.267361Z","steps":["trace[211310553] 'process raft request' (duration: 294.386504ms)","trace[211310553] 'compare' (duration: 51.487976ms)"],"step_count":2} Nov 5 04:47:41.267897 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:41.267501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:40.921353Z","time spent":"346.086069ms","remote":"10.0.0.33:44182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 5 04:47:41.347142 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:41.346703Z","caller":"traceutil/trace.go:171","msg":"trace[574159995] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:937; }","duration":"235.568282ms","start":"2025-11-05T04:47:41.111114Z","end":"2025-11-05T04:47:41.346682Z","steps":["trace[574159995] 'read index received' (duration: 104.631015ms)","trace[574159995] 'applied index is now lower than readState.Index' (duration: 130.936516ms)"],"step_count":2} Nov 5 04:47:41.347142 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:41.346726Z","caller":"traceutil/trace.go:171","msg":"trace[2122165163] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"386.122424ms","start":"2025-11-05T04:47:40.960587Z","end":"2025-11-05T04:47:41.346709Z","steps":["trace[2122165163] 'process raft request' (duration: 385.960579ms)"],"step_count":1} Nov 5 04:47:41.347142 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:41.346829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.704871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} Nov 5 04:47:41.347142 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:41.346836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:40.960541Z","time spent":"386.234606ms","remote":"10.0.0.33:44054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Nov 5 04:47:41.347464 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:41.346858Z","caller":"traceutil/trace.go:171","msg":"trace[1919288436] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:906; }","duration":"235.741329ms","start":"2025-11-05T04:47:41.111109Z","end":"2025-11-05T04:47:41.346851Z","steps":["trace[1919288436] 'agreement among raft nodes before linearized reading' (duration: 235.645909ms)"],"step_count":1} Nov 5 04:47:44.172626 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:44.172250Z","caller":"traceutil/trace.go:171","msg":"trace[234432284] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"191.032945ms","start":"2025-11-05T04:47:43.981190Z","end":"2025-11-05T04:47:44.172223Z","steps":["trace[234432284] 'process raft request' (duration: 129.034622ms)","trace[234432284] 'compare' (duration: 61.929413ms)"],"step_count":2} Nov 5 04:47:44.994173 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:44.993779Z","caller":"traceutil/trace.go:171","msg":"trace[588227583] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"207.450811ms","start":"2025-11-05T04:47:44.786288Z","end":"2025-11-05T04:47:44.993739Z","steps":["trace[588227583] 'process raft request' (duration: 169.741303ms)","trace[588227583] 'compare' (duration: 37.588829ms)"],"step_count":2} Nov 5 04:47:45.016972 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.016159Z","caller":"traceutil/trace.go:171","msg":"trace[1320845741] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:979; }","duration":"152.516255ms","start":"2025-11-05T04:47:44.863631Z","end":"2025-11-05T04:47:45.016147Z","steps":["trace[1320845741] 'read index received' (duration: 92.39534ms)","trace[1320845741] 'applied index is now lower than readState.Index' (duration: 60.120514ms)"],"step_count":2} Nov 5 04:47:45.016972 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.016204Z","caller":"traceutil/trace.go:171","msg":"trace[1378126808] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"206.865ms","start":"2025-11-05T04:47:44.809307Z","end":"2025-11-05T04:47:45.016172Z","steps":["trace[1378126808] 'process raft request' (duration: 206.784368ms)"],"step_count":1} Nov 5 04:47:45.016972 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.016305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.462023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-gxvgd.187502f009359da2\" limit:1 ","response":"range_response_count:1 size:788"} Nov 5 04:47:45.016972 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.016332Z","caller":"traceutil/trace.go:171","msg":"trace[2089749805] range","detail":"{range_begin:/registry/events/kube-system/cilium-gxvgd.187502f009359da2; range_end:; response_count:1; response_revision:947; }","duration":"152.50307ms","start":"2025-11-05T04:47:44.863824Z","end":"2025-11-05T04:47:45.016327Z","steps":["trace[2089749805] 'agreement among raft nodes before linearized reading' (duration: 152.398102ms)"],"step_count":1} Nov 5 04:47:45.016972 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.016365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.186074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 5 04:47:45.017225 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.016398Z","caller":"traceutil/trace.go:171","msg":"trace[714987254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"123.215339ms","start":"2025-11-05T04:47:44.893166Z","end":"2025-11-05T04:47:45.016382Z","steps":["trace[714987254] 'agreement among raft nodes before linearized reading' (duration: 123.16778ms)"],"step_count":1} Nov 5 04:47:45.017225 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.016430Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.677058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gxvgd\" limit:1 ","response":"range_response_count:1 size:18097"} Nov 5 04:47:45.017225 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.016553Z","caller":"traceutil/trace.go:171","msg":"trace[1670932881] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gxvgd; range_end:; response_count:1; response_revision:947; }","duration":"152.928652ms","start":"2025-11-05T04:47:44.863617Z","end":"2025-11-05T04:47:45.016545Z","steps":["trace[1670932881] 'agreement among raft nodes before linearized reading' (duration: 152.574985ms)"],"step_count":1} Nov 5 04:47:45.566448 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.565950Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.746523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} Nov 5 04:47:45.566448 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.566017Z","caller":"traceutil/trace.go:171","msg":"trace[1840176624] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:955; }","duration":"353.822676ms","start":"2025-11-05T04:47:45.212175Z","end":"2025-11-05T04:47:45.565998Z","steps":["trace[1840176624] 'count revisions from in-memory index tree' (duration: 353.673945ms)"],"step_count":1} Nov 5 04:47:45.566448 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.566046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:45.212162Z","time spent":"353.875776ms","remote":"10.0.0.33:44122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} Nov 5 04:47:45.566448 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.566055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.491363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:1 size:178171"} Nov 5 04:47:45.567023 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.566124Z","caller":"traceutil/trace.go:171","msg":"trace[1663343895] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:955; }","duration":"348.567477ms","start":"2025-11-05T04:47:45.217534Z","end":"2025-11-05T04:47:45.566102Z","steps":["trace[1663343895] 'range keys from in-memory index tree' (duration: 348.355608ms)"],"step_count":1} Nov 5 04:47:45.567023 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.566117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.144056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 ","response":"range_response_count:1 size:177928"} Nov 5 04:47:45.567023 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.566164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:45.217530Z","time spent":"348.622832ms","remote":"10.0.0.33:44010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":178195,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" limit:1 "} Nov 5 04:47:45.567023 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.566182Z","caller":"traceutil/trace.go:171","msg":"trace[621672081] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:955; }","duration":"349.221562ms","start":"2025-11-05T04:47:45.216942Z","end":"2025-11-05T04:47:45.566164Z","steps":["trace[621672081] 'range keys from in-memory index tree' (duration: 348.991127ms)"],"step_count":1} Nov 5 04:47:45.567154 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.566225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:45.216936Z","time spent":"349.280895ms","remote":"10.0.0.33:44010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":177952,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" limit:1 "} Nov 5 04:47:45.598095 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.597721Z","caller":"traceutil/trace.go:171","msg":"trace[534919762] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"211.686275ms","start":"2025-11-05T04:47:45.386024Z","end":"2025-11-05T04:47:45.597710Z","steps":["trace[534919762] 'read index received' (duration: 211.583381ms)","trace[534919762] 'applied index is now lower than readState.Index' (duration: 102.523µs)"],"step_count":2} Nov 5 04:47:45.598095 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.597828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.806892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} Nov 5 04:47:45.598095 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.597825Z","caller":"traceutil/trace.go:171","msg":"trace[583092992] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"369.551391ms","start":"2025-11-05T04:47:45.228256Z","end":"2025-11-05T04:47:45.597808Z","steps":["trace[583092992] 'process raft request' (duration: 369.364117ms)"],"step_count":1} Nov 5 04:47:45.598095 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:45.597853Z","caller":"traceutil/trace.go:171","msg":"trace[282624739] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:956; }","duration":"211.83706ms","start":"2025-11-05T04:47:45.386011Z","end":"2025-11-05T04:47:45.597848Z","steps":["trace[282624739] 'agreement among raft nodes before linearized reading' (duration: 211.760025ms)"],"step_count":1} Nov 5 04:47:45.598528 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:45.597913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:45.228239Z","time spent":"369.623607ms","remote":"10.0.0.33:44166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3860,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 5 04:47:51.070315 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.069689Z","caller":"traceutil/trace.go:171","msg":"trace[1673300599] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"272.683791ms","start":"2025-11-05T04:47:50.796990Z","end":"2025-11-05T04:47:51.069674Z","steps":["trace[1673300599] 'read index received' (duration: 261.28126ms)","trace[1673300599] 'applied index is now lower than readState.Index' (duration: 11.40193ms)"],"step_count":2} Nov 5 04:47:51.070315 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.069771Z","caller":"traceutil/trace.go:171","msg":"trace[1611573715] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"365.047416ms","start":"2025-11-05T04:47:50.704690Z","end":"2025-11-05T04:47:51.069737Z","steps":["trace[1611573715] 'process raft request' (duration: 353.591155ms)","trace[1611573715] 'compare' (duration: 11.325604ms)"],"step_count":2} Nov 5 04:47:51.070315 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.069829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.825549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-pm778.187502f0626209b5\" limit:1 ","response":"range_response_count:1 size:788"} Nov 5 04:47:51.070315 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.069851Z","caller":"traceutil/trace.go:171","msg":"trace[7110124] range","detail":"{range_begin:/registry/events/kube-system/cilium-pm778.187502f0626209b5; range_end:; response_count:1; response_revision:996; }","duration":"272.85851ms","start":"2025-11-05T04:47:50.796987Z","end":"2025-11-05T04:47:51.069846Z","steps":["trace[7110124] 'agreement among raft nodes before linearized reading' (duration: 272.774732ms)"],"step_count":1} Nov 5 04:47:51.071009 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.069888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.806519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} Nov 5 04:47:51.071009 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.069915Z","caller":"traceutil/trace.go:171","msg":"trace[1842209804] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:996; }","duration":"153.837548ms","start":"2025-11-05T04:47:50.916070Z","end":"2025-11-05T04:47:51.069907Z","steps":["trace[1842209804] 'agreement among raft nodes before linearized reading' (duration: 153.793945ms)"],"step_count":1} Nov 5 04:47:51.071009 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.069908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:50.704681Z","time spent":"365.147014ms","remote":"10.0.0.33:44182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18226,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 5 04:47:51.071009 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.069930Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.659224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Nov 5 04:47:51.071175 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.069974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.119011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-gxvgd.187502f009359da2\" limit:1 ","response":"range_response_count:1 size:788"} Nov 5 04:47:51.071175 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.069955Z","caller":"traceutil/trace.go:171","msg":"trace[371937243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"172.685454ms","start":"2025-11-05T04:47:50.897266Z","end":"2025-11-05T04:47:51.069951Z","steps":["trace[371937243] 'agreement among raft nodes before linearized reading' (duration: 172.649737ms)"],"step_count":1} Nov 5 04:47:51.071175 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.070007Z","caller":"traceutil/trace.go:171","msg":"trace[1668281973] range","detail":"{range_begin:/registry/events/kube-system/cilium-gxvgd.187502f009359da2; range_end:; response_count:1; response_revision:996; }","duration":"173.155551ms","start":"2025-11-05T04:47:50.896843Z","end":"2025-11-05T04:47:51.069999Z","steps":["trace[1668281973] 'agreement among raft nodes before linearized reading' (duration: 173.018462ms)"],"step_count":1} Nov 5 04:47:51.422679 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.422169Z","caller":"traceutil/trace.go:171","msg":"trace[913779075] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"256.482695ms","start":"2025-11-05T04:47:51.165668Z","end":"2025-11-05T04:47:51.422150Z","steps":["trace[913779075] 'process raft request' (duration: 191.705333ms)","trace[913779075] 'compare' (duration: 64.668387ms)"],"step_count":2} Nov 5 04:47:51.986038 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.985624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.916144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Nov 5 04:47:51.986038 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.985725Z","caller":"traceutil/trace.go:171","msg":"trace[1581686580] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"258.605439ms","start":"2025-11-05T04:47:51.727107Z","end":"2025-11-05T04:47:51.985712Z","steps":["trace[1581686580] 'read index received' (duration: 34.51678ms)","trace[1581686580] 'applied index is now lower than readState.Index' (duration: 224.087838ms)"],"step_count":2} Nov 5 04:47:51.986303 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.985777Z","caller":"traceutil/trace.go:171","msg":"trace[181601696] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"554.948998ms","start":"2025-11-05T04:47:51.430798Z","end":"2025-11-05T04:47:51.985747Z","steps":["trace[181601696] 'process raft request' (duration: 330.824159ms)","trace[181601696] 'compare' (duration: 223.792411ms)"],"step_count":2} Nov 5 04:47:51.986303 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.985822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.72244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/10.0.0.45.187502e62e3cf84e\" limit:1 ","response":"range_response_count:1 size:636"} Nov 5 04:47:51.986303 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.985841Z","caller":"traceutil/trace.go:171","msg":"trace[579197506] range","detail":"{range_begin:/registry/events/default/10.0.0.45.187502e62e3cf84e; range_end:; response_count:1; response_revision:1000; }","duration":"258.740844ms","start":"2025-11-05T04:47:51.727093Z","end":"2025-11-05T04:47:51.985834Z","steps":["trace[579197506] 'agreement among raft nodes before linearized reading' (duration: 258.656946ms)"],"step_count":1} Nov 5 04:47:51.986303 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:51.985896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-11-05T04:47:51.430774Z","time spent":"555.060487ms","remote":"10.0.0.33:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13175,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Nov 5 04:47:51.990798 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:51.990489Z","caller":"traceutil/trace.go:171","msg":"trace[1359850119] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"261.50529ms","start":"2025-11-05T04:47:51.728974Z","end":"2025-11-05T04:47:51.990480Z","steps":["trace[1359850119] 'process raft request' (duration: 261.42077ms)"],"step_count":1} Nov 5 04:47:52.210800 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:52.210213Z","caller":"traceutil/trace.go:171","msg":"trace[174725378] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"213.984569ms","start":"2025-11-05T04:47:51.996214Z","end":"2025-11-05T04:47:52.210199Z","steps":["trace[174725378] 'process raft request' (duration: 129.266654ms)","trace[174725378] 'compare' (duration: 84.547644ms)"],"step_count":2} Nov 5 04:47:52.210800 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:52.210197Z","caller":"traceutil/trace.go:171","msg":"trace[1171890965] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1037; }","duration":"191.674887ms","start":"2025-11-05T04:47:52.018505Z","end":"2025-11-05T04:47:52.210180Z","steps":["trace[1171890965] 'read index received' (duration: 19.693245ms)","trace[1171890965] 'applied index is now lower than readState.Index' (duration: 171.980871ms)"],"step_count":2} Nov 5 04:47:52.210800 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:52.210263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.741413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods\" limit:1 ","response":"range_response_count:0 size:5"} Nov 5 04:47:52.210800 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:52.210294Z","caller":"traceutil/trace.go:171","msg":"trace[135921648] range","detail":"{range_begin:/registry/pods; range_end:; response_count:0; response_revision:1004; }","duration":"191.785405ms","start":"2025-11-05T04:47:52.018501Z","end":"2025-11-05T04:47:52.210287Z","steps":["trace[135921648] 'agreement among raft nodes before linearized reading' (duration: 191.731364ms)"],"step_count":1} Nov 5 04:47:52.210800 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:52.210343Z","caller":"traceutil/trace.go:171","msg":"trace[1231266641] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"196.412965ms","start":"2025-11-05T04:47:52.013924Z","end":"2025-11-05T04:47:52.210337Z","steps":["trace[1231266641] 'process raft request' (duration: 196.217016ms)"],"step_count":1} Nov 5 04:47:52.211453 etcd-wrapper[1867]: {"level":"warn","ts":"2025-11-05T04:47:52.210379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.984145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-pm778.187502f0626209b5\" limit:1 ","response":"range_response_count:1 size:788"} Nov 5 04:47:52.211453 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:52.210401Z","caller":"traceutil/trace.go:171","msg":"trace[184088875] range","detail":"{range_begin:/registry/events/kube-system/cilium-pm778.187502f0626209b5; range_end:; response_count:1; response_revision:1004; }","duration":"170.006536ms","start":"2025-11-05T04:47:52.040387Z","end":"2025-11-05T04:47:52.210394Z","steps":["trace[184088875] 'agreement among raft nodes before linearized reading' (duration: 169.92354ms)"],"step_count":1} Nov 5 04:47:54.623665 etcd-wrapper[1867]: {"level":"info","ts":"2025-11-05T04:47:54.623298Z","caller":"traceutil/trace.go:171","msg":"trace[1487919781] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"114.739546ms","start":"2025-11-05T04:47:54.508541Z","end":"2025-11-05T04:47:54.623280Z","steps":["trace[1487919781] 'process raft request' (duration: 114.645899ms)"],"step_count":1}