{"level":"warn","ts":"2026-02-20T18:23:09.698989Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:23:09.699103Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-20T18:23:09.699275Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:23:09.699297Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-20T18:23:09.699325Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:23:09.700178Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-02-20T18:23:09.700272Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","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":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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":true,"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"} {"level":"info","ts":"2026-02-20T18:23:09.70521Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.666283ms"} {"level":"info","ts":"2026-02-20T18:23:09.712135Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-02-20T18:23:09.712234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:23:09.71226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-02-20T18:23:09.712288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:23:09.712297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-02-20T18:23:09.712332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-02-20T18:23:09.719953Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:23:09.721008Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:23:09.722107Z","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"} {"level":"info","ts":"2026-02-20T18:23:09.724163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:23:09.726307Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:23:09.726515Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:23:09.726655Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:23:09.726758Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:23:09.727041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-02-20T18:23:09.727165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-20T18:23:09.730005Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:23:09.730106Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-20T18:23:09.730171Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-20T18:23:09.73031Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:23:09.730351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:23:09.913577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:23:09.913639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:23:09.913686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-02-20T18:23:09.913712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:23:09.91373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-20T18:23:09.913811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-02-20T18:23:09.913857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-20T18:23:09.914666Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:09.914808Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:23:09.914816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:23:09.914904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:23:09.915225Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:23:09.915275Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:23:09.915437Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:09.915523Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:09.915553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:09.91912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:23:09.920143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-02-20T18:23:21.249451Z","caller":"traceutil/trace.go:171","msg":"trace[136843446] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"230.059796ms","start":"2026-02-20T18:23:21.019371Z","end":"2026-02-20T18:23:21.24943Z","steps":["trace[136843446] 'process raft request' (duration: 229.942756ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:26.172495Z","caller":"traceutil/trace.go:171","msg":"trace[318358535] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"156.859419ms","start":"2026-02-20T18:23:26.015597Z","end":"2026-02-20T18:23:26.172457Z","steps":["trace[318358535] 'read index received' (duration: 156.618718ms)","trace[318358535] 'applied index is now lower than readState.Index' (duration: 239.401µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:26.172547Z","caller":"traceutil/trace.go:171","msg":"trace[1470191775] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"240.646305ms","start":"2026-02-20T18:23:25.93188Z","end":"2026-02-20T18:23:26.172526Z","steps":["trace[1470191775] 'process raft request' (duration: 240.412553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:26.172726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.072891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:23:26.1728Z","caller":"traceutil/trace.go:171","msg":"trace[323743140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"157.193671ms","start":"2026-02-20T18:23:26.015588Z","end":"2026-02-20T18:23:26.172782Z","steps":["trace[323743140] 'agreement among raft nodes before linearized reading' (duration: 157.02999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:26.172828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.324737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:23:26.172896Z","caller":"traceutil/trace.go:171","msg":"trace[2117531950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"120.428457ms","start":"2026-02-20T18:23:26.052451Z","end":"2026-02-20T18:23:26.172879Z","steps":["trace[2117531950] 'agreement among raft nodes before linearized reading' (duration: 120.315796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:32.345397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.065115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:23:32.345461Z","caller":"traceutil/trace.go:171","msg":"trace[286523851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"109.180225ms","start":"2026-02-20T18:23:32.236267Z","end":"2026-02-20T18:23:32.345447Z","steps":["trace[286523851] 'range keys from in-memory index tree' (duration: 108.948714ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:36.468396Z","caller":"traceutil/trace.go:171","msg":"trace[1057249096] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"139.006875ms","start":"2026-02-20T18:23:36.32937Z","end":"2026-02-20T18:23:36.468377Z","steps":["trace[1057249096] 'read index received' (duration: 138.849894ms)","trace[1057249096] 'applied index is now lower than readState.Index' (duration: 156.301µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:36.468448Z","caller":"traceutil/trace.go:171","msg":"trace[1519276793] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"556.508245ms","start":"2026-02-20T18:23:35.911926Z","end":"2026-02-20T18:23:36.468434Z","steps":["trace[1519276793] 'process raft request' (duration: 556.348884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:36.468977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:35.911908Z","time spent":"556.575675ms","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:36.469278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.891211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:36.469374Z","caller":"traceutil/trace.go:171","msg":"trace[190282667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"140.042991ms","start":"2026-02-20T18:23:36.329315Z","end":"2026-02-20T18:23:36.469358Z","steps":["trace[190282667] 'agreement among raft nodes before linearized reading' (duration: 139.180386ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:42.604239Z","caller":"traceutil/trace.go:171","msg":"trace[148638492] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"106.387158ms","start":"2026-02-20T18:23:42.497825Z","end":"2026-02-20T18:23:42.604213Z","steps":["trace[148638492] 'read index received' (duration: 106.227149ms)","trace[148638492] 'applied index is now lower than readState.Index' (duration: 158.729µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:42.604344Z","caller":"traceutil/trace.go:171","msg":"trace[1000623395] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"107.305808ms","start":"2026-02-20T18:23:42.49701Z","end":"2026-02-20T18:23:42.604316Z","steps":["trace[1000623395] 'process raft request' (duration: 106.995201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:42.604417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.556167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:23:42.604481Z","caller":"traceutil/trace.go:171","msg":"trace[1793575198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"106.639871ms","start":"2026-02-20T18:23:42.497821Z","end":"2026-02-20T18:23:42.604461Z","steps":["trace[1793575198] 'agreement among raft nodes before linearized reading' (duration: 106.522715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:42.60458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.860499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:23:42.604632Z","caller":"traceutil/trace.go:171","msg":"trace[1547859549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"100.930513ms","start":"2026-02-20T18:23:42.503688Z","end":"2026-02-20T18:23:42.604619Z","steps":["trace[1547859549] 'agreement among raft nodes before linearized reading' (duration: 100.791635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:42.604638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.17397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:23:42.604756Z","caller":"traceutil/trace.go:171","msg":"trace[760366643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"102.310108ms","start":"2026-02-20T18:23:42.502413Z","end":"2026-02-20T18:23:42.604723Z","steps":["trace[760366643] 'agreement among raft nodes before linearized reading' (duration: 102.010221ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:44.843455Z","caller":"traceutil/trace.go:171","msg":"trace[1317244726] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"114.071081ms","start":"2026-02-20T18:23:44.729353Z","end":"2026-02-20T18:23:44.843424Z","steps":["trace[1317244726] 'process raft request' (duration: 94.14941ms)","trace[1317244726] 'compare' (duration: 19.441216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:49.387623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.824997ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:23:49.679681Z","caller":"traceutil/trace.go:171","msg":"trace[2082310530] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"277.6957ms","start":"2026-02-20T18:23:49.401966Z","end":"2026-02-20T18:23:49.679662Z","steps":["trace[2082310530] 'process raft request' (duration: 277.49297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:51.830634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:23:52.331873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:23:52.83269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:23:53.045071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.397121211s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:23:53.045668Z","caller":"traceutil/trace.go:171","msg":"trace[81615122] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"2.397829345s","start":"2026-02-20T18:23:50.647816Z","end":"2026-02-20T18:23:53.045645Z","steps":["trace[81615122] 'process raft request' (duration: 2.397638176s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:53.045917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:50.647776Z","time spent":"2.397986832s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:53.170525Z","caller":"traceutil/trace.go:171","msg":"trace[1022007236] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.522241595s","start":"2026-02-20T18:23:50.648226Z","end":"2026-02-20T18:23:53.170468Z","steps":["trace[1022007236] 'process raft request' (duration: 2.460770279s)","trace[1022007236] 'compare' (duration: 61.269697ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:53.170634Z","caller":"traceutil/trace.go:171","msg":"trace[813534248] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.519936461s","start":"2026-02-20T18:23:50.650684Z","end":"2026-02-20T18:23:53.17062Z","steps":["trace[813534248] 'process raft request' (duration: 2.519865828s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:53.171097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:50.650676Z","time spent":"2.520379613s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:53.170637Z","caller":"traceutil/trace.go:171","msg":"trace[1207347802] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"2.520499049s","start":"2026-02-20T18:23:50.650115Z","end":"2026-02-20T18:23:53.170614Z","steps":["trace[1207347802] 'process raft request' (duration: 2.520294059s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:53.171273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:50.650099Z","time spent":"2.521084127s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:53.170663Z","caller":"traceutil/trace.go:171","msg":"trace[1698880102] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"327.566304ms","start":"2026-02-20T18:23:52.843075Z","end":"2026-02-20T18:23:53.170642Z","steps":["trace[1698880102] 'process raft request' (duration: 327.501291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:53.171439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:52.843055Z","time spent":"328.347411ms","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:53.170738Z","caller":"traceutil/trace.go:171","msg":"trace[449133771] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:574; }","duration":"1.840814745s","start":"2026-02-20T18:23:51.329912Z","end":"2026-02-20T18:23:53.170727Z","steps":["trace[449133771] 'read index received' (duration: 1.715559224s)","trace[449133771] 'applied index is now lower than readState.Index' (duration: 125.254421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:53.170813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.840881638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:23:53.171723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054579856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:53.171758Z","caller":"traceutil/trace.go:171","msg":"trace[1051268780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"1.05464754s","start":"2026-02-20T18:23:52.1171Z","end":"2026-02-20T18:23:53.171748Z","steps":["trace[1051268780] 'agreement among raft nodes before linearized reading' (duration: 1.054592257s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:53.171786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:52.117084Z","time spent":"1.054695922s","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:23:53.172055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:50.648206Z","time spent":"2.522736969s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:53.171667Z","caller":"traceutil/trace.go:171","msg":"trace[1795109017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"1.841780381s","start":"2026-02-20T18:23:51.329872Z","end":"2026-02-20T18:23:53.171652Z","steps":["trace[1795109017] 'agreement among raft nodes before linearized reading' (duration: 1.840884109s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:53.172388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:51.329851Z","time spent":"1.842521186s","remote":"127.0.0.1:44754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:23:55.063982Z","caller":"traceutil/trace.go:171","msg":"trace[1796569386] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"523.838929ms","start":"2026-02-20T18:23:54.54011Z","end":"2026-02-20T18:23:55.063949Z","steps":["trace[1796569386] 'process raft request' (duration: 523.598848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:55.06411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:54.540086Z","time spent":"523.965745ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":834,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:23:57.82715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.109371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-20T18:23:57.827282Z","caller":"traceutil/trace.go:171","msg":"trace[2001885315] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"191.30886ms","start":"2026-02-20T18:23:57.635941Z","end":"2026-02-20T18:23:57.82725Z","steps":["trace[2001885315] 'range keys from in-memory index tree' (duration: 190.86634ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:17.523323Z","caller":"traceutil/trace.go:171","msg":"trace[2095722116] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"105.421721ms","start":"2026-02-20T18:24:17.417868Z","end":"2026-02-20T18:24:17.523289Z","steps":["trace[2095722116] 'process raft request' (duration: 89.025363ms)","trace[2095722116] 'compare' (duration: 16.243853ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:19.400255Z","caller":"traceutil/trace.go:171","msg":"trace[950119231] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"138.577223ms","start":"2026-02-20T18:24:19.261651Z","end":"2026-02-20T18:24:19.400228Z","steps":["trace[950119231] 'process raft request' (duration: 138.372716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:23.866826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.65544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:24:23.86693Z","caller":"traceutil/trace.go:171","msg":"trace[1479152258] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"357.732759ms","start":"2026-02-20T18:24:23.509181Z","end":"2026-02-20T18:24:23.866914Z","steps":["trace[1479152258] 'process raft request' (duration: 202.915393ms)","trace[1479152258] 'compare' (duration: 154.522846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:23.867089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.509131Z","time spent":"357.821182ms","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:25.51857Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.806456582s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:24:25.548524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.024624ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:25.548665Z","caller":"traceutil/trace.go:171","msg":"trace[1105261842] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:799; }","duration":"350.19052ms","start":"2026-02-20T18:24:25.198453Z","end":"2026-02-20T18:24:25.548644Z","steps":["trace[1105261842] 'range keys from in-memory index tree' (duration: 350.004934ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:25.549266Z","caller":"traceutil/trace.go:171","msg":"trace[2021750678] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"2.037377126s","start":"2026-02-20T18:24:23.511839Z","end":"2026-02-20T18:24:25.549217Z","steps":["trace[2021750678] 'process raft request' (duration: 2.007384678s)","trace[2021750678] 'compare' (duration: 28.449827ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:25.549427Z","caller":"traceutil/trace.go:171","msg":"trace[638686955] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:827; }","duration":"1.996901585s","start":"2026-02-20T18:24:23.55251Z","end":"2026-02-20T18:24:25.549412Z","steps":["trace[638686955] 'read index received' (duration: 159.607458ms)","trace[638686955] 'applied index is now lower than readState.Index' (duration: 1.837292487s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:25.549451Z","caller":"traceutil/trace.go:171","msg":"trace[570075625] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"2.035627037s","start":"2026-02-20T18:24:23.513805Z","end":"2026-02-20T18:24:25.549432Z","steps":["trace[570075625] 'process raft request' (duration: 2.03541475s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.549553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.511819Z","time spent":"2.037584723s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:25.549657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997136863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:25.549725Z","caller":"traceutil/trace.go:171","msg":"trace[568908285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"1.997239886s","start":"2026-02-20T18:24:23.55247Z","end":"2026-02-20T18:24:25.549709Z","steps":["trace[568908285] 'agreement among raft nodes before linearized reading' (duration: 1.997032239s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.549766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.552426Z","time spent":"1.997331078s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T18:24:25.549291Z","caller":"traceutil/trace.go:171","msg":"trace[690905097] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"2.036354881s","start":"2026-02-20T18:24:23.512912Z","end":"2026-02-20T18:24:25.549267Z","steps":["trace[690905097] 'process raft request' (duration: 2.036231407s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.549904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.5129Z","time spent":"2.036960521s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:25.549352Z","caller":"traceutil/trace.go:171","msg":"trace[474999413] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"2.036665992s","start":"2026-02-20T18:24:23.512674Z","end":"2026-02-20T18:24:25.54934Z","steps":["trace[474999413] 'process raft request' (duration: 2.036347701s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.550192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.512661Z","time spent":"2.037361664s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:25.549619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.513791Z","time spent":"2.035716299s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:25.567241Z","caller":"traceutil/trace.go:171","msg":"trace[1753217752] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.657733357s","start":"2026-02-20T18:24:23.909477Z","end":"2026-02-20T18:24:25.56721Z","steps":["trace[1753217752] 'process raft request' (duration: 1.657597173s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.567305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.896018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:24:25.56734Z","caller":"traceutil/trace.go:171","msg":"trace[842916254] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:804; }","duration":"945.992681ms","start":"2026-02-20T18:24:24.621339Z","end":"2026-02-20T18:24:25.567331Z","steps":["trace[842916254] 'agreement among raft nodes before linearized reading' (duration: 945.916438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.567368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:24.621309Z","time spent":"946.051333ms","remote":"127.0.0.1:45200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:25.567367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.909453Z","time spent":"1.657852631s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:25.56728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697471723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-20T18:24:25.567433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237735868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:25.567555Z","caller":"traceutil/trace.go:171","msg":"trace[830378746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"1.237912074s","start":"2026-02-20T18:24:24.329611Z","end":"2026-02-20T18:24:25.567523Z","steps":["trace[830378746] 'agreement among raft nodes before linearized reading' (duration: 1.237707068s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.567614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:24.329587Z","time spent":"1.238015178s","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:24:25.567568Z","caller":"traceutil/trace.go:171","msg":"trace[609533858] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:804; }","duration":"1.697716721s","start":"2026-02-20T18:24:23.869783Z","end":"2026-02-20T18:24:25.5675Z","steps":["trace[609533858] 'agreement among raft nodes before linearized reading' (duration: 1.69740312s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.56775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:23.869715Z","time spent":"1.698017631s","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-20T18:24:27.737936Z","caller":"traceutil/trace.go:171","msg":"trace[2064986531] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"178.065302ms","start":"2026-02-20T18:24:27.559848Z","end":"2026-02-20T18:24:27.737913Z","steps":["trace[2064986531] 'process raft request' (duration: 177.931518ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:27.751835Z","caller":"traceutil/trace.go:171","msg":"trace[1194468455] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:836; }","duration":"129.551468ms","start":"2026-02-20T18:24:27.622252Z","end":"2026-02-20T18:24:27.751804Z","steps":["trace[1194468455] 'read index received' (duration: 115.65299ms)","trace[1194468455] 'applied index is now lower than readState.Index' (duration: 13.896868ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:27.752012Z","caller":"traceutil/trace.go:171","msg":"trace[1206306020] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"192.112645ms","start":"2026-02-20T18:24:27.559867Z","end":"2026-02-20T18:24:27.751979Z","steps":["trace[1206306020] 'process raft request' (duration: 191.672661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:27.752195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.93719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:27.752263Z","caller":"traceutil/trace.go:171","msg":"trace[286154330] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"130.063815ms","start":"2026-02-20T18:24:27.622184Z","end":"2026-02-20T18:24:27.752248Z","steps":["trace[286154330] 'agreement among raft nodes before linearized reading' (duration: 129.872889ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:27.752445Z","caller":"traceutil/trace.go:171","msg":"trace[287216412] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"192.26185ms","start":"2026-02-20T18:24:27.560168Z","end":"2026-02-20T18:24:27.75243Z","steps":["trace[287216412] 'process raft request' (duration: 191.509225ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:27.752691Z","caller":"traceutil/trace.go:171","msg":"trace[281084778] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"192.57822ms","start":"2026-02-20T18:24:27.560096Z","end":"2026-02-20T18:24:27.752674Z","steps":["trace[281084778] 'process raft request' (duration: 191.654851ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:29.964057Z","caller":"traceutil/trace.go:171","msg":"trace[2006746323] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"173.46138ms","start":"2026-02-20T18:24:29.790573Z","end":"2026-02-20T18:24:29.964034Z","steps":["trace[2006746323] 'process raft request' (duration: 168.233125ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:29.988707Z","caller":"traceutil/trace.go:171","msg":"trace[1520405557] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"197.516329ms","start":"2026-02-20T18:24:29.791149Z","end":"2026-02-20T18:24:29.988665Z","steps":["trace[1520405557] 'process raft request' (duration: 197.183189ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:29.988803Z","caller":"traceutil/trace.go:171","msg":"trace[1787080949] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"197.626133ms","start":"2026-02-20T18:24:29.791158Z","end":"2026-02-20T18:24:29.988785Z","steps":["trace[1787080949] 'process raft request' (duration: 197.315963ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:29.988974Z","caller":"traceutil/trace.go:171","msg":"trace[1549119561] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:842; }","duration":"158.785135ms","start":"2026-02-20T18:24:29.829892Z","end":"2026-02-20T18:24:29.988677Z","steps":["trace[1549119561] 'read index received' (duration: 128.961073ms)","trace[1549119561] 'applied index is now lower than readState.Index' (duration: 29.823032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:29.990292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.382417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:29.990499Z","caller":"traceutil/trace.go:171","msg":"trace[1229148377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"160.47791ms","start":"2026-02-20T18:24:29.829849Z","end":"2026-02-20T18:24:29.990327Z","steps":["trace[1229148377] 'agreement among raft nodes before linearized reading' (duration: 159.151708ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:32.252513Z","caller":"traceutil/trace.go:171","msg":"trace[1697821419] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"248.753063ms","start":"2026-02-20T18:24:32.003722Z","end":"2026-02-20T18:24:32.252475Z","steps":["trace[1697821419] 'process raft request' (duration: 219.472465ms)","trace[1697821419] 'compare' (duration: 29.079842ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:32.2651Z","caller":"traceutil/trace.go:171","msg":"trace[237568913] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:848; }","duration":"219.197357ms","start":"2026-02-20T18:24:32.04588Z","end":"2026-02-20T18:24:32.265078Z","steps":["trace[237568913] 'read index received' (duration: 177.327044ms)","trace[237568913] 'applied index is now lower than readState.Index' (duration: 41.869383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:32.26532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.109678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:32.265392Z","caller":"traceutil/trace.go:171","msg":"trace[1140836211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"147.220631ms","start":"2026-02-20T18:24:32.11816Z","end":"2026-02-20T18:24:32.265381Z","steps":["trace[1140836211] 'agreement among raft nodes before linearized reading' (duration: 147.113188ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:32.265426Z","caller":"traceutil/trace.go:171","msg":"trace[709792362] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"258.508601ms","start":"2026-02-20T18:24:32.006703Z","end":"2026-02-20T18:24:32.265211Z","steps":["trace[709792362] 'process raft request' (duration: 258.247123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:32.265884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.987031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:32.265975Z","caller":"traceutil/trace.go:171","msg":"trace[689869742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"220.090894ms","start":"2026-02-20T18:24:32.045837Z","end":"2026-02-20T18:24:32.265928Z","steps":["trace[689869742] 'agreement among raft nodes before linearized reading' (duration: 219.382972ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:34.547818Z","caller":"traceutil/trace.go:171","msg":"trace[1913801176] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"243.771791ms","start":"2026-02-20T18:24:34.30402Z","end":"2026-02-20T18:24:34.547792Z","steps":["trace[1913801176] 'process raft request' (duration: 243.615077ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:34.552528Z","caller":"traceutil/trace.go:171","msg":"trace[1842251234] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"247.343418ms","start":"2026-02-20T18:24:34.305173Z","end":"2026-02-20T18:24:34.552516Z","steps":["trace[1842251234] 'process raft request' (duration: 247.316067ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:34.552667Z","caller":"traceutil/trace.go:171","msg":"trace[1947573408] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"248.230355ms","start":"2026-02-20T18:24:34.304399Z","end":"2026-02-20T18:24:34.55263Z","steps":["trace[1947573408] 'process raft request' (duration: 247.991838ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:36.695058Z","caller":"traceutil/trace.go:171","msg":"trace[1116347649] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"137.435216ms","start":"2026-02-20T18:24:36.557596Z","end":"2026-02-20T18:24:36.695031Z","steps":["trace[1116347649] 'read index received' (duration: 137.294782ms)","trace[1116347649] 'applied index is now lower than readState.Index' (duration: 139.444µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:36.696601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.984132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:36.696739Z","caller":"traceutil/trace.go:171","msg":"trace[2023871371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"139.192388ms","start":"2026-02-20T18:24:36.557536Z","end":"2026-02-20T18:24:36.696728Z","steps":["trace[2023871371] 'agreement among raft nodes before linearized reading' (duration: 138.752205ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:36.697623Z","caller":"traceutil/trace.go:171","msg":"trace[1184367024] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"141.148516ms","start":"2026-02-20T18:24:36.556313Z","end":"2026-02-20T18:24:36.697462Z","steps":["trace[1184367024] 'process raft request' (duration: 138.57295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:36.70192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.732501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:24:36.70199Z","caller":"traceutil/trace.go:171","msg":"trace[1136509367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"144.287758ms","start":"2026-02-20T18:24:36.557687Z","end":"2026-02-20T18:24:36.701975Z","steps":["trace[1136509367] 'agreement among raft nodes before linearized reading' (duration: 143.595097ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:38.868407Z","caller":"traceutil/trace.go:171","msg":"trace[1813162177] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"121.473387ms","start":"2026-02-20T18:24:38.746901Z","end":"2026-02-20T18:24:38.868374Z","steps":["trace[1813162177] 'read index received' (duration: 121.235301ms)","trace[1813162177] 'applied index is now lower than readState.Index' (duration: 237.076µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:38.868772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.847288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:38.868831Z","caller":"traceutil/trace.go:171","msg":"trace[315542419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:837; }","duration":"121.973792ms","start":"2026-02-20T18:24:38.746843Z","end":"2026-02-20T18:24:38.868817Z","steps":["trace[315542419] 'agreement among raft nodes before linearized reading' (duration: 121.682624ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:38.869429Z","caller":"traceutil/trace.go:171","msg":"trace[504474267] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"158.101375ms","start":"2026-02-20T18:24:38.710399Z","end":"2026-02-20T18:24:38.8685Z","steps":["trace[504474267] 'process raft request' (duration: 157.830867ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:41.16788Z","caller":"traceutil/trace.go:171","msg":"trace[835617725] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"193.096695ms","start":"2026-02-20T18:24:40.974749Z","end":"2026-02-20T18:24:41.167845Z","steps":["trace[835617725] 'read index received' (duration: 158.973468ms)","trace[835617725] 'applied index is now lower than readState.Index' (duration: 34.122037ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:41.16794Z","caller":"traceutil/trace.go:171","msg":"trace[474159401] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"200.202636ms","start":"2026-02-20T18:24:40.967711Z","end":"2026-02-20T18:24:41.167914Z","steps":["trace[474159401] 'process raft request' (duration: 165.945095ms)","trace[474159401] 'compare' (duration: 34.012464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:41.168081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.661896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:24:41.168132Z","caller":"traceutil/trace.go:171","msg":"trace[1393907863] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:843; }","duration":"150.79315ms","start":"2026-02-20T18:24:41.017327Z","end":"2026-02-20T18:24:41.16812Z","steps":["trace[1393907863] 'agreement among raft nodes before linearized reading' (duration: 150.688937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:41.168174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.400524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:24:41.168257Z","caller":"traceutil/trace.go:171","msg":"trace[626405499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"193.529167ms","start":"2026-02-20T18:24:40.97471Z","end":"2026-02-20T18:24:41.168239Z","steps":["trace[626405499] 'agreement among raft nodes before linearized reading' (duration: 193.298551ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:43.44313Z","caller":"traceutil/trace.go:171","msg":"trace[98367226] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"209.328991ms","start":"2026-02-20T18:24:43.233767Z","end":"2026-02-20T18:24:43.443096Z","steps":["trace[98367226] 'process raft request' (duration: 198.563446ms)","trace[98367226] 'compare' (duration: 10.593791ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:45.654751Z","caller":"traceutil/trace.go:171","msg":"trace[1052277336] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"202.89489ms","start":"2026-02-20T18:24:45.451756Z","end":"2026-02-20T18:24:45.654651Z","steps":["trace[1052277336] 'process raft request' (duration: 202.718916ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:46.585715Z","caller":"traceutil/trace.go:171","msg":"trace[465800474] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"106.919012ms","start":"2026-02-20T18:24:46.478769Z","end":"2026-02-20T18:24:46.585688Z","steps":["trace[465800474] 'process raft request' (duration: 106.740827ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:47.300212Z","caller":"traceutil/trace.go:171","msg":"trace[1947927732] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"111.825025ms","start":"2026-02-20T18:24:47.188353Z","end":"2026-02-20T18:24:47.300178Z","steps":["trace[1947927732] 'process raft request' (duration: 111.594059ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:47.447841Z","caller":"traceutil/trace.go:171","msg":"trace[133992523] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"117.618528ms","start":"2026-02-20T18:24:47.330189Z","end":"2026-02-20T18:24:47.447807Z","steps":["trace[133992523] 'read index received' (duration: 113.488539ms)","trace[133992523] 'applied index is now lower than readState.Index' (duration: 4.128429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:47.447999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.777224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:47.448042Z","caller":"traceutil/trace.go:171","msg":"trace[1579070753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"117.874285ms","start":"2026-02-20T18:24:47.330155Z","end":"2026-02-20T18:24:47.448029Z","steps":["trace[1579070753] 'agreement among raft nodes before linearized reading' (duration: 117.775733ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:47.448399Z","caller":"traceutil/trace.go:171","msg":"trace[781688044] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"143.497385ms","start":"2026-02-20T18:24:47.304868Z","end":"2026-02-20T18:24:47.448365Z","steps":["trace[781688044] 'process raft request' (duration: 138.869403ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:47.765559Z","caller":"traceutil/trace.go:171","msg":"trace[1041295566] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"100.958537ms","start":"2026-02-20T18:24:47.664573Z","end":"2026-02-20T18:24:47.765531Z","steps":["trace[1041295566] 'process raft request' (duration: 100.804483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:49.583861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.873898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:24:49.583934Z","caller":"traceutil/trace.go:171","msg":"trace[1311450714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"130.99836ms","start":"2026-02-20T18:24:49.45292Z","end":"2026-02-20T18:24:49.583918Z","steps":["trace[1311450714] 'range keys from in-memory index tree' (duration: 130.732814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:49.584153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.352038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:49.584169Z","caller":"traceutil/trace.go:171","msg":"trace[1479661186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"255.395309ms","start":"2026-02-20T18:24:49.328768Z","end":"2026-02-20T18:24:49.584164Z","steps":["trace[1479661186] 'range keys from in-memory index tree' (duration: 255.292447ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:49.720106Z","caller":"traceutil/trace.go:171","msg":"trace[1602804315] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"126.995567ms","start":"2026-02-20T18:24:49.593086Z","end":"2026-02-20T18:24:49.720081Z","steps":["trace[1602804315] 'process raft request' (duration: 126.876623ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:49.884762Z","caller":"traceutil/trace.go:171","msg":"trace[1480352055] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"110.342483ms","start":"2026-02-20T18:24:49.774383Z","end":"2026-02-20T18:24:49.884726Z","steps":["trace[1480352055] 'process raft request' (duration: 74.337676ms)","trace[1480352055] 'compare' (duration: 35.845573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:57.169804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:57.67108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:58.172298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:58.67261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:59.173651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:59.332479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999242571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:24:59.332598Z","caller":"traceutil/trace.go:171","msg":"trace[1320461909] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999396624s","start":"2026-02-20T18:24:57.333183Z","end":"2026-02-20T18:24:59.33258Z","steps":["trace[1320461909] 'agreement among raft nodes before linearized reading' (duration: 1.999261531s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:59.332705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:57.333167Z","time spent":"1.999524848s","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:24:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:24:59.674115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:25:00.174292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197826226853811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:25:00.642781Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.250870272s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:25:00.643404Z","caller":"traceutil/trace.go:171","msg":"trace[1047978801] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"4.251375334s","start":"2026-02-20T18:24:56.391811Z","end":"2026-02-20T18:25:00.643186Z","steps":["trace[1047978801] 'process raft request' (duration: 4.251233931s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.643602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:56.391785Z","time spent":"4.251679422s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:25:00.646804Z","caller":"traceutil/trace.go:171","msg":"trace[990536712] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"3.090640902s","start":"2026-02-20T18:24:57.556146Z","end":"2026-02-20T18:25:00.646787Z","steps":["trace[990536712] 'process raft request' (duration: 3.090464528s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.646915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:57.55612Z","time spent":"3.090738435s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:25:00.649029Z","caller":"traceutil/trace.go:171","msg":"trace[1078674858] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:919; }","duration":"3.979843954s","start":"2026-02-20T18:24:56.669172Z","end":"2026-02-20T18:25:00.649016Z","steps":["trace[1078674858] 'read index received' (duration: 3.973800131s)","trace[1078674858] 'applied index is now lower than readState.Index' (duration: 6.042733ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:00.649231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316518542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:00.64926Z","caller":"traceutil/trace.go:171","msg":"trace[602822087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"1.316577854s","start":"2026-02-20T18:24:59.332675Z","end":"2026-02-20T18:25:00.649253Z","steps":["trace[602822087] 'agreement among raft nodes before linearized reading' (duration: 1.316517872s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.649279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:59.332648Z","time spent":"1.316627614s","remote":"127.0.0.1:44754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:25:00.649259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.954634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:25:00.649331Z","caller":"traceutil/trace.go:171","msg":"trace[2086685638] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:886; }","duration":"563.070367ms","start":"2026-02-20T18:25:00.08625Z","end":"2026-02-20T18:25:00.64932Z","steps":["trace[2086685638] 'agreement among raft nodes before linearized reading' (duration: 562.973135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.649366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.086231Z","time spent":"563.124899ms","remote":"127.0.0.1:44926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:25:00.649464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.473271114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:25:00.6498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416748332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:00.64983Z","caller":"traceutil/trace.go:171","msg":"trace[1556539516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"1.416817104s","start":"2026-02-20T18:24:59.233005Z","end":"2026-02-20T18:25:00.649822Z","steps":["trace[1556539516] 'agreement among raft nodes before linearized reading' (duration: 1.416778223s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.649852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:59.232985Z","time spent":"1.416862515s","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:25:00.649822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.980665094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:25:00.649917Z","caller":"traceutil/trace.go:171","msg":"trace[475492320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"3.980796007s","start":"2026-02-20T18:24:56.669107Z","end":"2026-02-20T18:25:00.649903Z","steps":["trace[475492320] 'agreement among raft nodes before linearized reading' (duration: 3.980555431s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.649508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.138592169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T18:25:00.650046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:56.669087Z","time spent":"3.98089347s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:25:00.65008Z","caller":"traceutil/trace.go:171","msg":"trace[1164848640] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:886; }","duration":"2.139206564s","start":"2026-02-20T18:24:58.510866Z","end":"2026-02-20T18:25:00.650072Z","steps":["trace[1164848640] 'agreement among raft nodes before linearized reading' (duration: 2.138589939s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.650153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:58.510833Z","time spent":"2.139310397s","remote":"127.0.0.1:45124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:25:00.649817Z","caller":"traceutil/trace.go:171","msg":"trace[1506148238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"3.473671034s","start":"2026-02-20T18:24:57.176126Z","end":"2026-02-20T18:25:00.649798Z","steps":["trace[1506148238] 'agreement among raft nodes before linearized reading' (duration: 3.473041899s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.650401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.936375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:25:00.64958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.556625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:00.650668Z","caller":"traceutil/trace.go:171","msg":"trace[1186547899] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:886; }","duration":"667.208565ms","start":"2026-02-20T18:24:59.983404Z","end":"2026-02-20T18:25:00.650612Z","steps":["trace[1186547899] 'agreement among raft nodes before linearized reading' (duration: 665.898934ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:00.650703Z","caller":"traceutil/trace.go:171","msg":"trace[844513919] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:886; }","duration":"530.708782ms","start":"2026-02-20T18:25:00.119981Z","end":"2026-02-20T18:25:00.65069Z","steps":["trace[844513919] 'agreement among raft nodes before linearized reading' (duration: 529.564825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.650733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.119959Z","time spent":"530.764713ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:25:00.650735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:59.983339Z","time spent":"667.372118ms","remote":"127.0.0.1:45044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:25:00.650477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:57.176094Z","time spent":"3.47436275s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:25:00.649684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.727352808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-20T18:25:00.649711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389886802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:25:00.650975Z","caller":"traceutil/trace.go:171","msg":"trace[1413564896] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:886; }","duration":"1.391208242s","start":"2026-02-20T18:24:59.259759Z","end":"2026-02-20T18:25:00.650967Z","steps":["trace[1413564896] 'agreement among raft nodes before linearized reading' (duration: 1.389767758s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.651005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:59.259739Z","time spent":"1.391258544s","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":108,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:25:00.650965Z","caller":"traceutil/trace.go:171","msg":"trace[306099599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"2.728567396s","start":"2026-02-20T18:24:57.922324Z","end":"2026-02-20T18:25:00.650892Z","steps":["trace[306099599] 'agreement among raft nodes before linearized reading' (duration: 2.727283856s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.649732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.292332328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:25:00.651108Z","caller":"traceutil/trace.go:171","msg":"trace[1793877549] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:886; }","duration":"2.293768222s","start":"2026-02-20T18:24:58.357322Z","end":"2026-02-20T18:25:00.65109Z","steps":["trace[1793877549] 'agreement among raft nodes before linearized reading' (duration: 2.292303007s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.65107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:57.92227Z","time spent":"2.728780251s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:25:00.651165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:58.357291Z","time spent":"2.293851814s","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:25:00.651871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.887241915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:25:00.651921Z","caller":"traceutil/trace.go:171","msg":"trace[1762143529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"2.887326377s","start":"2026-02-20T18:24:57.764582Z","end":"2026-02-20T18:25:00.651908Z","steps":["trace[1762143529] 'agreement among raft nodes before linearized reading' (duration: 2.885171586s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:00.651958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:57.76456Z","time spent":"2.887386828s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:25:02.657568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000318566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:25:02.657692Z","caller":"traceutil/trace.go:171","msg":"trace[1829076018] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00047522s","start":"2026-02-20T18:25:00.657196Z","end":"2026-02-20T18:25:02.657671Z","steps":["trace[1829076018] 'agreement among raft nodes before linearized reading' (duration: 2.000324906s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:02.657743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.657191Z","time spent":"2.000542262s","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:25:02 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:25:04.118028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000871326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:25:04.118115Z","caller":"traceutil/trace.go:171","msg":"trace[248154649] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001012209s","start":"2026-02-20T18:25:02.117082Z","end":"2026-02-20T18:25:04.118094Z","steps":["trace[248154649] 'agreement among raft nodes before linearized reading' (duration: 2.000903776s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:04.118163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:02.117063Z","time spent":"2.001091141s","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:25:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:25:04.658064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000174215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:25:04.658144Z","caller":"traceutil/trace.go:171","msg":"trace[778689297] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000293477s","start":"2026-02-20T18:25:02.657833Z","end":"2026-02-20T18:25:04.658126Z","steps":["trace[778689297] 'agreement among raft nodes before linearized reading' (duration: 2.000193685s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:04.658189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:02.657821Z","time spent":"2.000359489s","remote":"127.0.0.1:44754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:25:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:25:05.561839Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.897735282s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:25:05.623325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.856712ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:05.623532Z","caller":"traceutil/trace.go:171","msg":"trace[1242095285] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:887; }","duration":"425.077837ms","start":"2026-02-20T18:25:05.198434Z","end":"2026-02-20T18:25:05.623512Z","steps":["trace[1242095285] 'range keys from in-memory index tree' (duration: 424.837952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.623801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.655608Z","time spent":"4.968188169s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:25:05.624072Z","caller":"traceutil/trace.go:171","msg":"trace[694463363] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"4.968032325s","start":"2026-02-20T18:25:00.656028Z","end":"2026-02-20T18:25:05.624061Z","steps":["trace[694463363] 'process raft request' (duration: 4.967517124s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.624211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.656019Z","time spent":"4.968104627s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:25:05.624231Z","caller":"traceutil/trace.go:171","msg":"trace[1145087762] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"4.967197756s","start":"2026-02-20T18:25:00.657019Z","end":"2026-02-20T18:25:05.624216Z","steps":["trace[1145087762] 'process raft request' (duration: 4.966724435s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.624335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.657012Z","time spent":"4.967250527s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:25:05.623808Z","caller":"traceutil/trace.go:171","msg":"trace[1117436639] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:922; }","duration":"4.966569861s","start":"2026-02-20T18:25:00.657213Z","end":"2026-02-20T18:25:05.623783Z","steps":["trace[1117436639] 'read index received' (duration: 6.935064ms)","trace[1117436639] 'applied index is now lower than readState.Index' (duration: 4.959633917s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:05.624509Z","caller":"traceutil/trace.go:171","msg":"trace[1418001169] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"4.968424104s","start":"2026-02-20T18:25:00.656074Z","end":"2026-02-20T18:25:05.624498Z","steps":["trace[1418001169] 'process raft request' (duration: 4.967627186s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.624572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.65607Z","time spent":"4.968471975s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:25:05.624624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.817379682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:25:05.624675Z","caller":"traceutil/trace.go:171","msg":"trace[286127029] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:890; }","duration":"4.817459334s","start":"2026-02-20T18:25:00.807204Z","end":"2026-02-20T18:25:05.624663Z","steps":["trace[286127029] 'agreement among raft nodes before linearized reading' (duration: 4.817377262s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.624706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:00.807187Z","time spent":"4.817511155s","remote":"127.0.0.1:44916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:25:05.624918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.722712368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:05.62497Z","caller":"traceutil/trace.go:171","msg":"trace[728971124] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:890; }","duration":"2.722798911s","start":"2026-02-20T18:25:02.902159Z","end":"2026-02-20T18:25:05.624958Z","steps":["trace[728971124] 'agreement among raft nodes before linearized reading' (duration: 2.722724999s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.625028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:02.902138Z","time spent":"2.722866213s","remote":"127.0.0.1:45176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:25:05.625067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.27763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:05.625107Z","caller":"traceutil/trace.go:171","msg":"trace[1194269745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"961.346981ms","start":"2026-02-20T18:25:04.663749Z","end":"2026-02-20T18:25:05.625096Z","steps":["trace[1194269745] 'agreement among raft nodes before linearized reading' (duration: 961.3008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.625136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:04.663729Z","time spent":"961.400653ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:25:05.625318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.957294796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:25:05.62536Z","caller":"traceutil/trace.go:171","msg":"trace[459380100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"2.957407428s","start":"2026-02-20T18:25:02.667943Z","end":"2026-02-20T18:25:05.625351Z","steps":["trace[459380100] 'agreement among raft nodes before linearized reading' (duration: 2.957272114s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.625389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:02.667918Z","time spent":"2.957464519s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:25:05.624974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.262945024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T18:25:05.625535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.977614529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:25:05.625588Z","caller":"traceutil/trace.go:171","msg":"trace[883125646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:890; }","duration":"2.97769118s","start":"2026-02-20T18:25:02.647887Z","end":"2026-02-20T18:25:05.625578Z","steps":["trace[883125646] 'agreement among raft nodes before linearized reading' (duration: 2.977573327s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.625624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:02.647841Z","time spent":"2.977776622s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:25:05.625032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.483742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:05.625933Z","caller":"traceutil/trace.go:171","msg":"trace[1681844861] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:890; }","duration":"109.414803ms","start":"2026-02-20T18:25:05.516494Z","end":"2026-02-20T18:25:05.625909Z","steps":["trace[1681844861] 'agreement among raft nodes before linearized reading' (duration: 108.514893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.626061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.517097229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-02-20T18:25:05.626888Z","caller":"traceutil/trace.go:171","msg":"trace[408182701] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:890; }","duration":"4.517744724s","start":"2026-02-20T18:25:01.108905Z","end":"2026-02-20T18:25:05.626649Z","steps":["trace[408182701] 'agreement among raft nodes before linearized reading' (duration: 4.516841544s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.627636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:01.108883Z","time spent":"4.518703795s","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329954,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-20T18:25:05.625542Z","caller":"traceutil/trace.go:171","msg":"trace[292968681] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:890; }","duration":"2.263547468s","start":"2026-02-20T18:25:03.361986Z","end":"2026-02-20T18:25:05.625533Z","steps":["trace[292968681] 'agreement among raft nodes before linearized reading' (duration: 2.262880723s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:05.632109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:03.361964Z","time spent":"2.270084606s","remote":"127.0.0.1:44780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"info","ts":"2026-02-20T18:25:05.94898Z","caller":"traceutil/trace.go:171","msg":"trace[258454021] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"257.34202ms","start":"2026-02-20T18:25:05.691607Z","end":"2026-02-20T18:25:05.948949Z","steps":["trace[258454021] 'process raft request' (duration: 235.836182ms)","trace[258454021] 'compare' (duration: 21.325084ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:05.967616Z","caller":"traceutil/trace.go:171","msg":"trace[535542191] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"273.488378ms","start":"2026-02-20T18:25:05.694113Z","end":"2026-02-20T18:25:05.967601Z","steps":["trace[535542191] 'process raft request' (duration: 273.339554ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:05.96761Z","caller":"traceutil/trace.go:171","msg":"trace[1340607863] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:931; }","duration":"237.093081ms","start":"2026-02-20T18:25:05.730489Z","end":"2026-02-20T18:25:05.967582Z","steps":["trace[1340607863] 'read index received' (duration: 196.974731ms)","trace[1340607863] 'applied index is now lower than readState.Index' (duration: 40.11672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:05.96818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.682794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-02-20T18:25:05.968223Z","caller":"traceutil/trace.go:171","msg":"trace[741031289] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:895; }","duration":"237.738725ms","start":"2026-02-20T18:25:05.730473Z","end":"2026-02-20T18:25:05.968212Z","steps":["trace[741031289] 'agreement among raft nodes before linearized reading' (duration: 237.391808ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:07.949066Z","caller":"traceutil/trace.go:171","msg":"trace[510286607] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"248.292495ms","start":"2026-02-20T18:25:07.700739Z","end":"2026-02-20T18:25:07.949031Z","steps":["trace[510286607] 'process raft request' (duration: 235.721395ms)","trace[510286607] 'compare' (duration: 12.366496ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:07.976923Z","caller":"traceutil/trace.go:171","msg":"trace[906209085] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"274.795178ms","start":"2026-02-20T18:25:07.7021Z","end":"2026-02-20T18:25:07.976895Z","steps":["trace[906209085] 'process raft request' (duration: 274.626904ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:10.254201Z","caller":"traceutil/trace.go:171","msg":"trace[292804407] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"206.131716ms","start":"2026-02-20T18:25:10.048043Z","end":"2026-02-20T18:25:10.254175Z","steps":["trace[292804407] 'process raft request' (duration: 171.523342ms)","trace[292804407] 'compare' (duration: 34.487451ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:12.023618Z","caller":"traceutil/trace.go:171","msg":"trace[1412694905] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"128.629651ms","start":"2026-02-20T18:25:11.894948Z","end":"2026-02-20T18:25:12.023577Z","steps":["trace[1412694905] 'process raft request' (duration: 94.384263ms)","trace[1412694905] 'compare' (duration: 34.051734ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:12.531395Z","caller":"traceutil/trace.go:171","msg":"trace[1615631253] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"124.359531ms","start":"2026-02-20T18:25:12.407009Z","end":"2026-02-20T18:25:12.531368Z","steps":["trace[1615631253] 'read index received' (duration: 124.087715ms)","trace[1615631253] 'applied index is now lower than readState.Index' (duration: 270.696µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:12.531511Z","caller":"traceutil/trace.go:171","msg":"trace[809334943] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"267.933094ms","start":"2026-02-20T18:25:12.26355Z","end":"2026-02-20T18:25:12.531483Z","steps":["trace[809334943] 'process raft request' (duration: 267.646388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:12.531681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.662378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-20T18:25:12.531811Z","caller":"traceutil/trace.go:171","msg":"trace[1535579425] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:913; }","duration":"124.847861ms","start":"2026-02-20T18:25:12.406948Z","end":"2026-02-20T18:25:12.531796Z","steps":["trace[1535579425] 'agreement among raft nodes before linearized reading' (duration: 124.526394ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:13.749438Z","caller":"traceutil/trace.go:171","msg":"trace[185452423] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"189.285725ms","start":"2026-02-20T18:25:13.560097Z","end":"2026-02-20T18:25:13.749382Z","steps":["trace[185452423] 'process raft request' (duration: 90.627102ms)","trace[185452423] 'compare' (duration: 98.440818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:14.159731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.269537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:25:14.159883Z","caller":"traceutil/trace.go:171","msg":"trace[1420019909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"131.527542ms","start":"2026-02-20T18:25:14.028304Z","end":"2026-02-20T18:25:14.159832Z","steps":["trace[1420019909] 'range keys from in-memory index tree' (duration: 130.995991ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:14.917181Z","caller":"traceutil/trace.go:171","msg":"trace[1376946963] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"197.353405ms","start":"2026-02-20T18:25:14.719766Z","end":"2026-02-20T18:25:14.917119Z","steps":["trace[1376946963] 'read index received' (duration: 197.167751ms)","trace[1376946963] 'applied index is now lower than readState.Index' (duration: 184.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:14.91724Z","caller":"traceutil/trace.go:171","msg":"trace[1249526195] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"376.2427ms","start":"2026-02-20T18:25:14.540961Z","end":"2026-02-20T18:25:14.917203Z","steps":["trace[1249526195] 'process raft request' (duration: 376.002475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:14.91737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.60734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:25:14.917414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:14.540927Z","time spent":"376.395513ms","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:25:14.91744Z","caller":"traceutil/trace.go:171","msg":"trace[304058907] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:919; }","duration":"197.732363ms","start":"2026-02-20T18:25:14.719688Z","end":"2026-02-20T18:25:14.91742Z","steps":["trace[304058907] 'agreement among raft nodes before linearized reading' (duration: 197.58869ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:15.955101Z","caller":"traceutil/trace.go:171","msg":"trace[1820075095] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"101.178274ms","start":"2026-02-20T18:25:15.853896Z","end":"2026-02-20T18:25:15.955074Z","steps":["trace[1820075095] 'process raft request' (duration: 101.020311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:16.109874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.865208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:25:16.109955Z","caller":"traceutil/trace.go:171","msg":"trace[556530677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"107.999261ms","start":"2026-02-20T18:25:16.001938Z","end":"2026-02-20T18:25:16.109937Z","steps":["trace[556530677] 'range keys from in-memory index tree' (duration: 107.663334ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:16.374754Z","caller":"traceutil/trace.go:171","msg":"trace[1802177505] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"181.484503ms","start":"2026-02-20T18:25:16.193241Z","end":"2026-02-20T18:25:16.374725Z","steps":["trace[1802177505] 'process raft request' (duration: 125.69475ms)","trace[1802177505] 'compare' (duration: 55.679381ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:16.718698Z","caller":"traceutil/trace.go:171","msg":"trace[869453176] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"123.967223ms","start":"2026-02-20T18:25:16.594712Z","end":"2026-02-20T18:25:16.718679Z","steps":["trace[869453176] 'process raft request' (duration: 123.841231ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:18.115245Z","caller":"traceutil/trace.go:171","msg":"trace[1173286017] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"117.226978ms","start":"2026-02-20T18:25:17.997979Z","end":"2026-02-20T18:25:18.115206Z","steps":["trace[1173286017] 'process raft request' (duration: 56.017641ms)","trace[1173286017] 'compare' (duration: 61.040554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:18.448368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.356472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:25:18.448482Z","caller":"traceutil/trace.go:171","msg":"trace[1670262644] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"264.342589ms","start":"2026-02-20T18:25:18.184121Z","end":"2026-02-20T18:25:18.448463Z","steps":["trace[1670262644] 'process raft request' (duration: 70.793424ms)","trace[1670262644] 'compare' (duration: 193.140536ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:18.501328Z","caller":"traceutil/trace.go:171","msg":"trace[1892641171] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"292.721192ms","start":"2026-02-20T18:25:18.20858Z","end":"2026-02-20T18:25:18.501302Z","steps":["trace[1892641171] 'read index received' (duration: 46.309391ms)","trace[1892641171] 'applied index is now lower than readState.Index' (duration: 246.410771ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:18.501363Z","caller":"traceutil/trace.go:171","msg":"trace[808977690] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"316.121093ms","start":"2026-02-20T18:25:18.185217Z","end":"2026-02-20T18:25:18.501338Z","steps":["trace[808977690] 'process raft request' (duration: 315.909198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:18.501518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:18.185199Z","time spent":"316.229125ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:25:18.50158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.125445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:18.501622Z","caller":"traceutil/trace.go:171","msg":"trace[1021219186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"172.211097ms","start":"2026-02-20T18:25:18.329393Z","end":"2026-02-20T18:25:18.501604Z","steps":["trace[1021219186] 'agreement among raft nodes before linearized reading' (duration: 172.047854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:18.501628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.140899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:25:18.501629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.046028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:25:18.501679Z","caller":"traceutil/trace.go:171","msg":"trace[864413247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"124.223261ms","start":"2026-02-20T18:25:18.377442Z","end":"2026-02-20T18:25:18.501665Z","steps":["trace[864413247] 'agreement among raft nodes before linearized reading' (duration: 124.039908ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:18.501688Z","caller":"traceutil/trace.go:171","msg":"trace[844498120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"293.152751ms","start":"2026-02-20T18:25:18.208521Z","end":"2026-02-20T18:25:18.501674Z","steps":["trace[844498120] 'agreement among raft nodes before linearized reading' (duration: 292.908276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:18.747213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.774456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:25:18.748424Z","caller":"traceutil/trace.go:171","msg":"trace[972751151] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"239.976248ms","start":"2026-02-20T18:25:18.507966Z","end":"2026-02-20T18:25:18.747943Z","steps":["trace[972751151] 'process raft request' (duration: 239.401626ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:18.748475Z","caller":"traceutil/trace.go:171","msg":"trace[1757717706] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"242.299166ms","start":"2026-02-20T18:25:18.506158Z","end":"2026-02-20T18:25:18.748457Z","steps":["trace[1757717706] 'process raft request' (duration: 53.211092ms)","trace[1757717706] 'compare' (duration: 187.677505ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:19.101865Z","caller":"traceutil/trace.go:171","msg":"trace[20029403] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"202.009965ms","start":"2026-02-20T18:25:18.899729Z","end":"2026-02-20T18:25:19.101739Z","steps":["trace[20029403] 'read index received' (duration: 129.334163ms)","trace[20029403] 'applied index is now lower than readState.Index' (duration: 72.674412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:19.102071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.318061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-20T18:25:19.101917Z","caller":"traceutil/trace.go:171","msg":"trace[1908087584] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"202.579866ms","start":"2026-02-20T18:25:18.899298Z","end":"2026-02-20T18:25:19.101878Z","steps":["trace[1908087584] 'process raft request' (duration: 129.779391ms)","trace[1908087584] 'compare' (duration: 72.507229ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:19.102121Z","caller":"traceutil/trace.go:171","msg":"trace[456424107] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:934; }","duration":"202.406203ms","start":"2026-02-20T18:25:18.899704Z","end":"2026-02-20T18:25:19.10211Z","steps":["trace[456424107] 'agreement among raft nodes before linearized reading' (duration: 202.249839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:19.102452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.165963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:25:19.102486Z","caller":"traceutil/trace.go:171","msg":"trace[648143406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"138.235435ms","start":"2026-02-20T18:25:18.964242Z","end":"2026-02-20T18:25:19.102478Z","steps":["trace[648143406] 'agreement among raft nodes before linearized reading' (duration: 138.146633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:19.357188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.257917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:25:19.357312Z","caller":"traceutil/trace.go:171","msg":"trace[1841191441] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"226.393216ms","start":"2026-02-20T18:25:19.130896Z","end":"2026-02-20T18:25:19.357289Z","steps":["trace[1841191441] 'process raft request' (duration: 118.904095ms)","trace[1841191441] 'compare' (duration: 107.140504ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:19.376187Z","caller":"traceutil/trace.go:171","msg":"trace[330281193] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"240.494193ms","start":"2026-02-20T18:25:19.135665Z","end":"2026-02-20T18:25:19.376159Z","steps":["trace[330281193] 'read index received' (duration: 114.084555ms)","trace[330281193] 'applied index is now lower than readState.Index' (duration: 126.408418ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:19.376244Z","caller":"traceutil/trace.go:171","msg":"trace[1713512322] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"240.934582ms","start":"2026-02-20T18:25:19.135287Z","end":"2026-02-20T18:25:19.376222Z","steps":["trace[1713512322] 'process raft request' (duration: 240.739628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:19.376383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.706728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:19.376528Z","caller":"traceutil/trace.go:171","msg":"trace[2008760053] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:938; }","duration":"240.855361ms","start":"2026-02-20T18:25:19.135656Z","end":"2026-02-20T18:25:19.376511Z","steps":["trace[2008760053] 'agreement among raft nodes before linearized reading' (duration: 240.648147ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:19.595746Z","caller":"traceutil/trace.go:171","msg":"trace[1202239101] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"177.60945ms","start":"2026-02-20T18:25:19.418114Z","end":"2026-02-20T18:25:19.595723Z","steps":["trace[1202239101] 'read index received' (duration: 145.552997ms)","trace[1202239101] 'applied index is now lower than readState.Index' (duration: 32.055623ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:19.595786Z","caller":"traceutil/trace.go:171","msg":"trace[1126701229] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"180.992569ms","start":"2026-02-20T18:25:19.414766Z","end":"2026-02-20T18:25:19.595759Z","steps":["trace[1126701229] 'process raft request' (duration: 148.900965ms)","trace[1126701229] 'compare' (duration: 31.977142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:19.595858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.728303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-20T18:25:19.595884Z","caller":"traceutil/trace.go:171","msg":"trace[2018458380] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01; range_end:; response_count:1; response_revision:940; }","duration":"177.774203ms","start":"2026-02-20T18:25:19.4181Z","end":"2026-02-20T18:25:19.595874Z","steps":["trace[2018458380] 'agreement among raft nodes before linearized reading' (duration: 177.715922ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:21.039902Z","caller":"traceutil/trace.go:171","msg":"trace[605994576] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"232.44099ms","start":"2026-02-20T18:25:20.807438Z","end":"2026-02-20T18:25:21.039879Z","steps":["trace[605994576] 'process raft request' (duration: 232.311457ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:21.060941Z","caller":"traceutil/trace.go:171","msg":"trace[1432660344] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"252.727437ms","start":"2026-02-20T18:25:20.808186Z","end":"2026-02-20T18:25:21.060913Z","steps":["trace[1432660344] 'process raft request' (duration: 252.512013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:21.16524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.373977ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-20T18:25:21.371115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.377957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:25:21.371384Z","caller":"traceutil/trace.go:171","msg":"trace[1021624030] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"153.434894ms","start":"2026-02-20T18:25:21.217887Z","end":"2026-02-20T18:25:21.371322Z","steps":["trace[1021624030] 'process raft request' (duration: 51.75183ms)","trace[1021624030] 'compare' (duration: 101.195414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:21.719089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.980343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:21.719202Z","caller":"traceutil/trace.go:171","msg":"trace[769520861] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:949; }","duration":"133.151606ms","start":"2026-02-20T18:25:21.586025Z","end":"2026-02-20T18:25:21.719176Z","steps":["trace[769520861] 'count revisions from in-memory index tree' (duration: 132.882901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:21.71913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.768067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-20T18:25:21.719376Z","caller":"traceutil/trace.go:171","msg":"trace[1774286348] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01; range_end:; response_count:1; response_revision:949; }","duration":"115.053003ms","start":"2026-02-20T18:25:21.604302Z","end":"2026-02-20T18:25:21.719355Z","steps":["trace[1774286348] 'range keys from in-memory index tree' (duration: 114.588814ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:22.166552Z","caller":"traceutil/trace.go:171","msg":"trace[1756009301] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"160.736547ms","start":"2026-02-20T18:25:22.005781Z","end":"2026-02-20T18:25:22.166518Z","steps":["trace[1756009301] 'process raft request' (duration: 160.556284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:22.857914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.142477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-20T18:25:22.858042Z","caller":"traceutil/trace.go:171","msg":"trace[1800138946] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01; range_end:; response_count:1; response_revision:951; }","duration":"134.26548ms","start":"2026-02-20T18:25:22.723713Z","end":"2026-02-20T18:25:22.857978Z","steps":["trace[1800138946] 'range keys from in-memory index tree' (duration: 133.953654ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:23.263326Z","caller":"traceutil/trace.go:171","msg":"trace[362774796] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"137.436184ms","start":"2026-02-20T18:25:23.125858Z","end":"2026-02-20T18:25:23.263294Z","steps":["trace[362774796] 'process raft request' (duration: 38.611765ms)","trace[362774796] 'compare' (duration: 98.649625ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:23.507283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.523005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-20T18:25:23.507372Z","caller":"traceutil/trace.go:171","msg":"trace[1103320965] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:954; }","duration":"143.625117ms","start":"2026-02-20T18:25:23.363723Z","end":"2026-02-20T18:25:23.507348Z","steps":["trace[1103320965] 'range keys from in-memory index tree' (duration: 143.360822ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:24.276621Z","caller":"traceutil/trace.go:171","msg":"trace[1043826853] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"100.917376ms","start":"2026-02-20T18:25:24.17567Z","end":"2026-02-20T18:25:24.276587Z","steps":["trace[1043826853] 'process raft request' (duration: 100.727973ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:24.71325Z","caller":"traceutil/trace.go:171","msg":"trace[216012966] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"103.232131ms","start":"2026-02-20T18:25:24.609993Z","end":"2026-02-20T18:25:24.713225Z","steps":["trace[216012966] 'process raft request' (duration: 102.942666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:25.33091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.53966ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:25:25.330959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.580101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:25:25.331027Z","caller":"traceutil/trace.go:171","msg":"trace[1519112393] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:958; }","duration":"132.671993ms","start":"2026-02-20T18:25:25.198339Z","end":"2026-02-20T18:25:25.331011Z","steps":["trace[1519112393] 'range keys from in-memory index tree' (duration: 132.52452ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:25.331054Z","caller":"traceutil/trace.go:171","msg":"trace[770020070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"110.738284ms","start":"2026-02-20T18:25:25.220291Z","end":"2026-02-20T18:25:25.331029Z","steps":["trace[770020070] 'range keys from in-memory index tree' (duration: 110.487639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:25.689653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.285653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:25:25.689758Z","caller":"traceutil/trace.go:171","msg":"trace[286627836] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"351.595912ms","start":"2026-02-20T18:25:25.338142Z","end":"2026-02-20T18:25:25.689738Z","steps":["trace[286627836] 'process raft request' (duration: 104.146776ms)","trace[286627836] 'compare' (duration: 247.074239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:25:25.689835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:25.338117Z","time spent":"351.675544ms","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:25:26.07833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.911387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-20T18:25:26.078446Z","caller":"traceutil/trace.go:171","msg":"trace[11690397] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24d281c0-9d9c-472a-b7ed-55df7df71a01; range_end:; response_count:1; response_revision:960; }","duration":"202.079619ms","start":"2026-02-20T18:25:25.876341Z","end":"2026-02-20T18:25:26.078421Z","steps":["trace[11690397] 'range keys from in-memory index tree' (duration: 201.707753ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:27.246904Z","caller":"traceutil/trace.go:171","msg":"trace[619853056] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"108.975501ms","start":"2026-02-20T18:25:27.137904Z","end":"2026-02-20T18:25:27.24688Z","steps":["trace[619853056] 'process raft request' (duration: 107.467782ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:29.095851Z","caller":"traceutil/trace.go:171","msg":"trace[1513733336] transaction","detail":"{read_only:false; number_of_response:1; response_revision:973; }","duration":"100.682677ms","start":"2026-02-20T18:25:28.995144Z","end":"2026-02-20T18:25:29.095827Z","steps":["trace[1513733336] 'process raft request' (duration: 100.521864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:33.653008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.940452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:25:33.653087Z","caller":"traceutil/trace.go:171","msg":"trace[223997657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"135.071845ms","start":"2026-02-20T18:25:33.517997Z","end":"2026-02-20T18:25:33.653069Z","steps":["trace[223997657] 'range keys from in-memory index tree' (duration: 134.727208ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:41.022846Z","caller":"traceutil/trace.go:171","msg":"trace[88676295] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"172.573852ms","start":"2026-02-20T18:25:40.850234Z","end":"2026-02-20T18:25:41.022808Z","steps":["trace[88676295] 'process raft request' (duration: 172.394709ms)"],"step_count":1}