{"level":"warn","ts":"2026-02-27T20:27:21.033789Z","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-27T20:27:21.033873Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-27T20:27:21.033931Z","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-27T20:27:21.033944Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-27T20:27:21.033963Z","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-27T20:27:21.034618Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-02-27T20:27:21.034687Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-27T20:27:21.041593Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.712718ms"} {"level":"info","ts":"2026-02-27T20:27:21.056899Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-02-27T20:27:21.057071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:27:21.057101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-02-27T20:27:21.05712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:27:21.057137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-02-27T20:27:21.057196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-02-27T20:27:21.067006Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:27:21.069204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:27:21.070308Z","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-27T20:27:21.073043Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:27:21.077726Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:27:21.077953Z","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-27T20:27:21.078082Z","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-27T20:27:21.078197Z","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-27T20:27:21.078781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-02-27T20:27:21.080091Z","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-27T20:27:21.080324Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:27:21.080364Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:27:21.080463Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-27T20:27:21.080484Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-27T20:27:21.08218Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-27T20:27:21.960973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:27:21.961051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:27:21.961091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-02-27T20:27:21.961119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:27:21.961132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-27T20:27:21.961153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-02-27T20:27:21.961184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-27T20:27:21.963793Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:27:21.964576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:27:21.964611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:27:21.964577Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:27:21.964949Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:27:21.964976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:27:21.965893Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:27:21.966044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:27:21.96619Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:27:21.966443Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-02-27T20:27:21.967478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-27T20:27:51.05584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.817039ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:27:51.055984Z","caller":"traceutil/trace.go:171","msg":"trace[1714938839] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"928.370257ms","start":"2026-02-27T20:27:50.127595Z","end":"2026-02-27T20:27:51.055965Z","steps":["trace[1714938839] 'read index received' (duration: 14.191268ms)","trace[1714938839] 'applied index is now lower than readState.Index' (duration: 914.177469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:51.056187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.61071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-02-27T20:27:51.056224Z","caller":"traceutil/trace.go:171","msg":"trace[1118751170] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:510; }","duration":"375.711072ms","start":"2026-02-27T20:27:50.680504Z","end":"2026-02-27T20:27:51.056215Z","steps":["trace[1118751170] 'agreement among raft nodes before linearized reading' (duration: 375.597229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:51.0562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.587572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T20:27:51.05627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:50.680482Z","time spent":"375.780425ms","remote":"127.0.0.1:60062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-27T20:27:51.056304Z","caller":"traceutil/trace.go:171","msg":"trace[393585798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"928.737636ms","start":"2026-02-27T20:27:50.127538Z","end":"2026-02-27T20:27:51.056275Z","steps":["trace[393585798] 'agreement among raft nodes before linearized reading' (duration: 928.488499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:51.056347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:50.12751Z","time spent":"928.828029ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:27:51.056376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.149917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:51.05647Z","caller":"traceutil/trace.go:171","msg":"trace[1128537210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"317.276109ms","start":"2026-02-27T20:27:50.739167Z","end":"2026-02-27T20:27:51.056443Z","steps":["trace[1128537210] 'agreement among raft nodes before linearized reading' (duration: 317.113835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:51.056519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:50.739153Z","time spent":"317.357922ms","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:27:57.720163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.699127ms","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-27T20:27:57.720261Z","caller":"traceutil/trace.go:171","msg":"trace[2002160209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"187.850771ms","start":"2026-02-27T20:27:57.53239Z","end":"2026-02-27T20:27:57.720241Z","steps":["trace[2002160209] 'range keys from in-memory index tree' (duration: 187.586374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:58.270491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.620028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8fhfj\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-02-27T20:27:58.270558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.831822ms","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-27T20:27:58.27063Z","caller":"traceutil/trace.go:171","msg":"trace[461100270] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8fhfj; range_end:; response_count:1; response_revision:532; }","duration":"387.833185ms","start":"2026-02-27T20:27:57.882765Z","end":"2026-02-27T20:27:58.270598Z","steps":["trace[461100270] 'range keys from in-memory index tree' (duration: 387.477105ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:58.270667Z","caller":"traceutil/trace.go:171","msg":"trace[2025515672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"237.028368ms","start":"2026-02-27T20:27:58.033611Z","end":"2026-02-27T20:27:58.27064Z","steps":["trace[2025515672] 'range keys from in-memory index tree' (duration: 236.678708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:58.27069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:57.882742Z","time spent":"387.937697ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-8fhfj\" "} {"level":"info","ts":"2026-02-27T20:27:58.417041Z","caller":"traceutil/trace.go:171","msg":"trace[1885896762] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"141.642173ms","start":"2026-02-27T20:27:58.27537Z","end":"2026-02-27T20:27:58.417013Z","steps":["trace[1885896762] 'process raft request' (duration: 141.51595ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:58.87178Z","caller":"traceutil/trace.go:171","msg":"trace[940151274] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"129.532061ms","start":"2026-02-27T20:27:58.742176Z","end":"2026-02-27T20:27:58.871708Z","steps":["trace[940151274] 'read index received' (duration: 129.023467ms)","trace[940151274] 'applied index is now lower than readState.Index' (duration: 507.144µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:58.871957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.751207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:58.872001Z","caller":"traceutil/trace.go:171","msg":"trace[1227749586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"129.87646ms","start":"2026-02-27T20:27:58.742108Z","end":"2026-02-27T20:27:58.871984Z","steps":["trace[1227749586] 'agreement among raft nodes before linearized reading' (duration: 129.771857ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:58.872272Z","caller":"traceutil/trace.go:171","msg":"trace[1000389591] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"588.611878ms","start":"2026-02-27T20:27:58.28363Z","end":"2026-02-27T20:27:58.872242Z","steps":["trace[1000389591] 'process raft request' (duration: 587.765616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:58.872929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:58.283595Z","time spent":"588.744802ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:59.327226Z","caller":"traceutil/trace.go:171","msg":"trace[1067338422] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"164.589354ms","start":"2026-02-27T20:27:59.162612Z","end":"2026-02-27T20:27:59.327201Z","steps":["trace[1067338422] 'process raft request' (duration: 164.433749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:00.307347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314185121293726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:28:00.807542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314185121293726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:28:00.863818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.116307519s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:28:00.864218Z","caller":"traceutil/trace.go:171","msg":"trace[413769667] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.116785943s","start":"2026-02-27T20:27:59.74741Z","end":"2026-02-27T20:28:00.864196Z","steps":["trace[413769667] 'process raft request' (duration: 1.116647109s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:00.864373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:59.747388Z","time spent":"1.116886865s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:01.761412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.002598ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:28:01.761514Z","caller":"traceutil/trace.go:171","msg":"trace[1829974858] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:553; }","duration":"1.954670334s","start":"2026-02-27T20:27:59.806827Z","end":"2026-02-27T20:28:01.761497Z","steps":["trace[1829974858] 'read index received' (duration: 1.057288361s)","trace[1829974858] 'applied index is now lower than readState.Index' (duration: 897.380343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:01.761657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022153928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:28:01.761658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.954828139s","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-27T20:28:01.761696Z","caller":"traceutil/trace.go:171","msg":"trace[1275469210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"1.02225073s","start":"2026-02-27T20:28:00.739431Z","end":"2026-02-27T20:28:01.761682Z","steps":["trace[1275469210] 'agreement among raft nodes before linearized reading' (duration: 1.022199489s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:01.761785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:00.739387Z","time spent":"1.022390185s","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:28:01.761711Z","caller":"traceutil/trace.go:171","msg":"trace[1010574834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"1.954951892s","start":"2026-02-27T20:27:59.806746Z","end":"2026-02-27T20:28:01.761698Z","steps":["trace[1010574834] 'agreement among raft nodes before linearized reading' (duration: 1.954810188s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:01.761864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:59.806673Z","time spent":"1.955175068s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:28:01.762051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.371845ms","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-27T20:28:01.762117Z","caller":"traceutil/trace.go:171","msg":"trace[1530308451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"430.450597ms","start":"2026-02-27T20:28:01.331632Z","end":"2026-02-27T20:28:01.762083Z","steps":["trace[1530308451] 'agreement among raft nodes before linearized reading' (duration: 429.984724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:01.762175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:01.331611Z","time spent":"430.55268ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:01.762279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341344809s","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-27T20:28:01.762323Z","caller":"traceutil/trace.go:171","msg":"trace[839637334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"1.341427501s","start":"2026-02-27T20:28:00.420885Z","end":"2026-02-27T20:28:01.762312Z","steps":["trace[839637334] 'agreement among raft nodes before linearized reading' (duration: 1.340701182s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:01.762352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:00.420863Z","time spent":"1.341481962s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:28:07.063458Z","caller":"traceutil/trace.go:171","msg":"trace[872895065] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"121.54719ms","start":"2026-02-27T20:28:06.941887Z","end":"2026-02-27T20:28:07.063434Z","steps":["trace[872895065] 'process raft request' (duration: 91.335897ms)","trace[872895065] 'compare' (duration: 30.021848ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:07.063636Z","caller":"traceutil/trace.go:171","msg":"trace[948017154] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"114.630695ms","start":"2026-02-27T20:28:06.94898Z","end":"2026-02-27T20:28:07.063611Z","steps":["trace[948017154] 'read index received' (duration: 4.578691ms)","trace[948017154] 'applied index is now lower than readState.Index' (duration: 110.049374ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:07.063672Z","caller":"traceutil/trace.go:171","msg":"trace[1908330212] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"112.944571ms","start":"2026-02-27T20:28:06.950717Z","end":"2026-02-27T20:28:07.063662Z","steps":["trace[1908330212] 'process raft request' (duration: 112.665363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:07.063892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.879002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8fhfj\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-02-27T20:28:07.063993Z","caller":"traceutil/trace.go:171","msg":"trace[533896397] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8fhfj; range_end:; response_count:1; response_revision:608; }","duration":"115.013956ms","start":"2026-02-27T20:28:06.948953Z","end":"2026-02-27T20:28:07.063967Z","steps":["trace[533896397] 'agreement among raft nodes before linearized reading' (duration: 114.79887ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:07.202844Z","caller":"traceutil/trace.go:171","msg":"trace[1653638573] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"126.141081ms","start":"2026-02-27T20:28:07.076669Z","end":"2026-02-27T20:28:07.202811Z","steps":["trace[1653638573] 'process raft request' (duration: 125.933615ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:07.208256Z","caller":"traceutil/trace.go:171","msg":"trace[2138292523] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"129.656655ms","start":"2026-02-27T20:28:07.078573Z","end":"2026-02-27T20:28:07.20823Z","steps":["trace[2138292523] 'process raft request' (duration: 129.535762ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:12.77157Z","caller":"traceutil/trace.go:171","msg":"trace[1633877085] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"108.687418ms","start":"2026-02-27T20:28:12.662853Z","end":"2026-02-27T20:28:12.77154Z","steps":["trace[1633877085] 'process raft request' (duration: 102.987246ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:12.859182Z","caller":"traceutil/trace.go:171","msg":"trace[1312080861] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:682; }","duration":"119.446924ms","start":"2026-02-27T20:28:12.739683Z","end":"2026-02-27T20:28:12.859129Z","steps":["trace[1312080861] 'read index received' (duration: 26.170776ms)","trace[1312080861] 'applied index is now lower than readState.Index' (duration: 93.274298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:12.859377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.67168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:12.859423Z","caller":"traceutil/trace.go:171","msg":"trace[17609909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"119.806384ms","start":"2026-02-27T20:28:12.739605Z","end":"2026-02-27T20:28:12.859412Z","steps":["trace[17609909] 'agreement among raft nodes before linearized reading' (duration: 119.67373ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:12.859526Z","caller":"traceutil/trace.go:171","msg":"trace[2063345263] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"191.198101ms","start":"2026-02-27T20:28:12.668289Z","end":"2026-02-27T20:28:12.859487Z","steps":["trace[2063345263] 'process raft request' (duration: 190.666727ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:12.936911Z","caller":"traceutil/trace.go:171","msg":"trace[973387754] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"155.007118ms","start":"2026-02-27T20:28:12.781864Z","end":"2026-02-27T20:28:12.936871Z","steps":["trace[973387754] 'process raft request' (duration: 154.71369ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:19.230572Z","caller":"traceutil/trace.go:171","msg":"trace[332573012] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"215.042544ms","start":"2026-02-27T20:28:19.015501Z","end":"2026-02-27T20:28:19.230544Z","steps":["trace[332573012] 'process raft request' (duration: 214.88416ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:20.351381Z","caller":"traceutil/trace.go:171","msg":"trace[110177434] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"539.905035ms","start":"2026-02-27T20:28:19.811448Z","end":"2026-02-27T20:28:20.351353Z","steps":["trace[110177434] 'process raft request' (duration: 539.760572ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:20.351502Z","caller":"traceutil/trace.go:171","msg":"trace[1556020739] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:744; }","duration":"490.078327ms","start":"2026-02-27T20:28:19.861364Z","end":"2026-02-27T20:28:20.351442Z","steps":["trace[1556020739] 'read index received' (duration: 490.056406ms)","trace[1556020739] 'applied index is now lower than readState.Index' (duration: 18.271µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:20.351552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:19.811425Z","time spent":"540.036338ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:20.352106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.717283ms","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-27T20:28:20.35226Z","caller":"traceutil/trace.go:171","msg":"trace[876334316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"490.921428ms","start":"2026-02-27T20:28:19.861312Z","end":"2026-02-27T20:28:20.352234Z","steps":["trace[876334316] 'agreement among raft nodes before linearized reading' (duration: 490.268411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:20.35236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:19.861291Z","time spent":"491.053292ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:28:22.068046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369706761s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:28:22.068306Z","caller":"traceutil/trace.go:171","msg":"trace[1794558419] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"1.716677747s","start":"2026-02-27T20:28:20.351599Z","end":"2026-02-27T20:28:22.068276Z","steps":["trace[1794558419] 'read index received' (duration: 346.836492ms)","trace[1794558419] 'applied index is now lower than readState.Index' (duration: 1.369839164s)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:22.068363Z","caller":"traceutil/trace.go:171","msg":"trace[1122459521] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"1.689441646s","start":"2026-02-27T20:28:20.378902Z","end":"2026-02-27T20:28:22.068344Z","steps":["trace[1122459521] 'process raft request' (duration: 1.689262562s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.068505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:20.378877Z","time spent":"1.689528409s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:22.068571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.183906208s","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-27T20:28:22.068613Z","caller":"traceutil/trace.go:171","msg":"trace[666322025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"2.18401531s","start":"2026-02-27T20:28:19.884582Z","end":"2026-02-27T20:28:22.068597Z","steps":["trace[666322025] 'agreement among raft nodes before linearized reading' (duration: 2.183834375s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.068573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.280034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:28:22.068654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:19.884557Z","time spent":"2.184088562s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:28:22.068682Z","caller":"traceutil/trace.go:171","msg":"trace[824396162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"329.456929ms","start":"2026-02-27T20:28:21.739198Z","end":"2026-02-27T20:28:22.068655Z","steps":["trace[824396162] 'agreement among raft nodes before linearized reading' (duration: 329.268004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.068669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.969458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:28:22.068667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.788116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-27T20:28:22.068725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.739171Z","time spent":"329.545322ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:28:22.068763Z","caller":"traceutil/trace.go:171","msg":"trace[1911862207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"826.95338ms","start":"2026-02-27T20:28:21.241788Z","end":"2026-02-27T20:28:22.068741Z","steps":["trace[1911862207] 'agreement among raft nodes before linearized reading' (duration: 826.681713ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:22.068763Z","caller":"traceutil/trace.go:171","msg":"trace[1344370654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"624.099402ms","start":"2026-02-27T20:28:21.444636Z","end":"2026-02-27T20:28:22.068735Z","steps":["trace[1344370654] 'agreement among raft nodes before linearized reading' (duration: 623.957258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.068734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.176387391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T20:28:22.068812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.44462Z","time spent":"624.183133ms","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:22.068812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.24175Z","time spent":"827.053292ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T20:28:22.068859Z","caller":"traceutil/trace.go:171","msg":"trace[1672366795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"2.176577326s","start":"2026-02-27T20:28:19.892249Z","end":"2026-02-27T20:28:22.068826Z","steps":["trace[1672366795] 'agreement among raft nodes before linearized reading' (duration: 2.176153915s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.068921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:19.892233Z","time spent":"2.176677919s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:28:22.222532Z","caller":"traceutil/trace.go:171","msg":"trace[828465275] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"148.378988ms","start":"2026-02-27T20:28:22.0741Z","end":"2026-02-27T20:28:22.222479Z","steps":["trace[828465275] 'process raft request' (duration: 98.246821ms)","trace[828465275] 'compare' (duration: 49.317756ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:22.232925Z","caller":"traceutil/trace.go:171","msg":"trace[1186310374] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"158.193034ms","start":"2026-02-27T20:28:22.074721Z","end":"2026-02-27T20:28:22.232914Z","steps":["trace[1186310374] 'process raft request' (duration: 158.092121ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:22.233271Z","caller":"traceutil/trace.go:171","msg":"trace[490650428] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"158.378959ms","start":"2026-02-27T20:28:22.074877Z","end":"2026-02-27T20:28:22.233256Z","steps":["trace[490650428] 'process raft request' (duration: 158.012209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.803204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.756215ms","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-27T20:28:24.803291Z","caller":"traceutil/trace.go:171","msg":"trace[261196597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"729.888639ms","start":"2026-02-27T20:28:24.073383Z","end":"2026-02-27T20:28:24.803272Z","steps":["trace[261196597] 'range keys from in-memory index tree' (duration: 729.592921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.803348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.073369Z","time spent":"729.958111ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:28:24.80363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.278848ms","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-27T20:28:24.803747Z","caller":"traceutil/trace.go:171","msg":"trace[923991596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"575.409401ms","start":"2026-02-27T20:28:24.228283Z","end":"2026-02-27T20:28:24.803692Z","steps":["trace[923991596] 'range keys from in-memory index tree' (duration: 575.104923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.803786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.228266Z","time spent":"575.512254ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:28:24.803848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558877692s","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":"info","ts":"2026-02-27T20:28:24.803888Z","caller":"traceutil/trace.go:171","msg":"trace[1051987752] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:725; }","duration":"1.558987405s","start":"2026-02-27T20:28:23.24489Z","end":"2026-02-27T20:28:24.803877Z","steps":["trace[1051987752] 'count revisions from in-memory index tree' (duration: 1.558780789s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.803932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:23.244866Z","time spent":"1.559056616s","remote":"127.0.0.1:60188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:28:24.804223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.048232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-27T20:28:24.804292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007031586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:24.804354Z","caller":"traceutil/trace.go:171","msg":"trace[1752306971] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:725; }","duration":"2.007147539s","start":"2026-02-27T20:28:22.797193Z","end":"2026-02-27T20:28:24.80434Z","steps":["trace[1752306971] 'count revisions from in-memory index tree' (duration: 2.006924953s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.804376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.815802ms","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-27T20:28:24.804406Z","caller":"traceutil/trace.go:171","msg":"trace[1724588447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"565.874562ms","start":"2026-02-27T20:28:24.238523Z","end":"2026-02-27T20:28:24.804397Z","steps":["trace[1724588447] 'range keys from in-memory index tree' (duration: 565.705509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.804407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.061813025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:28:24.804439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.238506Z","time spent":"565.927174ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:24.804402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:22.797163Z","time spent":"2.007228581s","remote":"127.0.0.1:60284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:28:24.804289Z","caller":"traceutil/trace.go:171","msg":"trace[1037858692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"384.171405ms","start":"2026-02-27T20:28:24.420103Z","end":"2026-02-27T20:28:24.804275Z","steps":["trace[1037858692] 'range keys from in-memory index tree' (duration: 383.96298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.805068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.420079Z","time spent":"384.953266ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:24.804226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.132176ms","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-27T20:28:24.80527Z","caller":"traceutil/trace.go:171","msg":"trace[1132864258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"568.216374ms","start":"2026-02-27T20:28:24.237038Z","end":"2026-02-27T20:28:24.805254Z","steps":["trace[1132864258] 'range keys from in-memory index tree' (duration: 566.937811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.805306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.237013Z","time spent":"568.282216ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:28:24.804441Z","caller":"traceutil/trace.go:171","msg":"trace[2022241650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"2.061886886s","start":"2026-02-27T20:28:22.742548Z","end":"2026-02-27T20:28:24.804435Z","steps":["trace[2022241650] 'range keys from in-memory index tree' (duration: 2.06167402s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.805579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:22.742522Z","time spent":"2.063039966s","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:28:45.508576Z","caller":"traceutil/trace.go:171","msg":"trace[1571406787] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"499.360898ms","start":"2026-02-27T20:28:45.008941Z","end":"2026-02-27T20:28:45.508302Z","steps":["trace[1571406787] 'read index received' (duration: 499.271106ms)","trace[1571406787] 'applied index is now lower than readState.Index' (duration: 88.692µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:45.508687Z","caller":"traceutil/trace.go:171","msg":"trace[1686243006] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"513.434565ms","start":"2026-02-27T20:28:44.995202Z","end":"2026-02-27T20:28:45.508637Z","steps":["trace[1686243006] 'process raft request' (duration: 512.967933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:45.508873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:44.995154Z","time spent":"513.625189ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:45.509217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.249041ms","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-27T20:28:45.509296Z","caller":"traceutil/trace.go:171","msg":"trace[25822364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"500.359483ms","start":"2026-02-27T20:28:45.008918Z","end":"2026-02-27T20:28:45.509277Z","steps":["trace[25822364] 'agreement among raft nodes before linearized reading' (duration: 499.762418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:45.510196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:45.008906Z","time spent":"500.58611ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:28:45.652196Z","caller":"traceutil/trace.go:171","msg":"trace[1805957226] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"143.519522ms","start":"2026-02-27T20:28:45.508651Z","end":"2026-02-27T20:28:45.652171Z","steps":["trace[1805957226] 'read index received' (duration: 101.058625ms)","trace[1805957226] 'applied index is now lower than readState.Index' (duration: 42.459397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:45.652314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.364234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T20:28:45.652348Z","caller":"traceutil/trace.go:171","msg":"trace[1958219122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"635.459806ms","start":"2026-02-27T20:28:45.016879Z","end":"2026-02-27T20:28:45.652339Z","steps":["trace[1958219122] 'agreement among raft nodes before linearized reading' (duration: 635.305142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:45.652357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.514284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T20:28:45.652382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:45.016854Z","time spent":"635.520888ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:45.652348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.374728ms","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":"warn","ts":"2026-02-27T20:28:45.652438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.73813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-27T20:28:45.652499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.891347ms","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-27T20:28:45.652575Z","caller":"traceutil/trace.go:171","msg":"trace[144200912] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:790; }","duration":"575.935886ms","start":"2026-02-27T20:28:45.076609Z","end":"2026-02-27T20:28:45.652545Z","steps":["trace[144200912] 'agreement among raft nodes before linearized reading' (duration: 575.615427ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:45.652477Z","caller":"traceutil/trace.go:171","msg":"trace[2145916287] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:790; }","duration":"306.596343ms","start":"2026-02-27T20:28:45.345851Z","end":"2026-02-27T20:28:45.652447Z","steps":["trace[2145916287] 'agreement among raft nodes before linearized reading' (duration: 306.329836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:45.652768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:45.345827Z","time spent":"306.894931ms","remote":"127.0.0.1:60100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:28:45.652402Z","caller":"traceutil/trace.go:171","msg":"trace[2038906116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"636.585966ms","start":"2026-02-27T20:28:45.015804Z","end":"2026-02-27T20:28:45.65239Z","steps":["trace[2038906116] 'agreement among raft nodes before linearized reading' (duration: 636.459622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:45.653043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:45.015793Z","time spent":"637.208372ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:28:45.652499Z","caller":"traceutil/trace.go:171","msg":"trace[1501396918] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"254.588917ms","start":"2026-02-27T20:28:45.3979Z","end":"2026-02-27T20:28:45.652489Z","steps":["trace[1501396918] 'process raft request' (duration: 211.693609ms)","trace[1501396918] 'compare' (duration: 42.454987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:45.652625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:45.076585Z","time spent":"576.030408ms","remote":"127.0.0.1:59908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"info","ts":"2026-02-27T20:28:45.652599Z","caller":"traceutil/trace.go:171","msg":"trace[1382808781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"636.020111ms","start":"2026-02-27T20:28:45.016552Z","end":"2026-02-27T20:28:45.652572Z","steps":["trace[1382808781] 'agreement among raft nodes before linearized reading' (duration: 635.732074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:45.653462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:45.016544Z","time spent":"636.906464ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:28:45.661697Z","caller":"traceutil/trace.go:171","msg":"trace[128026817] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"146.828018ms","start":"2026-02-27T20:28:45.51485Z","end":"2026-02-27T20:28:45.661678Z","steps":["trace[128026817] 'process raft request' (duration: 146.728135ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:45.823792Z","caller":"traceutil/trace.go:171","msg":"trace[1351685249] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"164.832007ms","start":"2026-02-27T20:28:45.658924Z","end":"2026-02-27T20:28:45.823756Z","steps":["trace[1351685249] 'process raft request' (duration: 164.726724ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:45.823844Z","caller":"traceutil/trace.go:171","msg":"trace[1524452658] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"166.362677ms","start":"2026-02-27T20:28:45.657431Z","end":"2026-02-27T20:28:45.823794Z","steps":["trace[1524452658] 'process raft request' (duration: 165.835903ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:45.824316Z","caller":"traceutil/trace.go:171","msg":"trace[471484266] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"166.054549ms","start":"2026-02-27T20:28:45.658236Z","end":"2026-02-27T20:28:45.824291Z","steps":["trace[471484266] 'process raft request' (duration: 165.30752ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:47.720716Z","caller":"traceutil/trace.go:171","msg":"trace[1667930656] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"202.732215ms","start":"2026-02-27T20:28:47.517957Z","end":"2026-02-27T20:28:47.720689Z","steps":["trace[1667930656] 'process raft request' (duration: 202.238432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:48.278639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.533849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:48.278789Z","caller":"traceutil/trace.go:171","msg":"trace[68703218] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"447.885017ms","start":"2026-02-27T20:28:47.83088Z","end":"2026-02-27T20:28:48.278765Z","steps":["trace[68703218] 'read index received' (duration: 344.026989ms)","trace[68703218] 'applied index is now lower than readState.Index' (duration: 103.855327ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:48.278993Z","caller":"traceutil/trace.go:171","msg":"trace[336134218] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"551.214751ms","start":"2026-02-27T20:28:47.727756Z","end":"2026-02-27T20:28:48.278971Z","steps":["trace[336134218] 'process raft request' (duration: 447.22723ms)","trace[336134218] 'compare' (duration: 102.919813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:48.27901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.110562ms","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-27T20:28:48.279064Z","caller":"traceutil/trace.go:171","msg":"trace[1026717623] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"448.191545ms","start":"2026-02-27T20:28:47.830857Z","end":"2026-02-27T20:28:48.279049Z","steps":["trace[1026717623] 'agreement among raft nodes before linearized reading' (duration: 447.953519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:48.279103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:47.727687Z","time spent":"551.366915ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:48.279119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:47.830846Z","time spent":"448.260866ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:48.279133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.245406ms","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-27T20:28:48.279187Z","caller":"traceutil/trace.go:171","msg":"trace[224770122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"448.346409ms","start":"2026-02-27T20:28:47.830827Z","end":"2026-02-27T20:28:48.279173Z","steps":["trace[224770122] 'agreement among raft nodes before linearized reading' (duration: 448.055842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:48.279233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:47.830803Z","time spent":"448.41815ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:48.279321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.440461ms","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-27T20:28:48.279356Z","caller":"traceutil/trace.go:171","msg":"trace[135825216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"448.519404ms","start":"2026-02-27T20:28:47.830827Z","end":"2026-02-27T20:28:48.279346Z","steps":["trace[135825216] 'agreement among raft nodes before linearized reading' (duration: 448.424031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:48.279384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:47.830802Z","time spent":"448.575064ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:28:48.57121Z","caller":"traceutil/trace.go:171","msg":"trace[1650023762] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"286.466528ms","start":"2026-02-27T20:28:48.284719Z","end":"2026-02-27T20:28:48.571186Z","steps":["trace[1650023762] 'process raft request' (duration: 286.285354ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:48.571537Z","caller":"traceutil/trace.go:171","msg":"trace[1418444848] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"286.272744ms","start":"2026-02-27T20:28:48.285242Z","end":"2026-02-27T20:28:48.571515Z","steps":["trace[1418444848] 'process raft request' (duration: 285.905984ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:48.61608Z","caller":"traceutil/trace.go:171","msg":"trace[514210935] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"314.033067ms","start":"2026-02-27T20:28:48.302015Z","end":"2026-02-27T20:28:48.616048Z","steps":["trace[514210935] 'process raft request' (duration: 313.849412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:48.616418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:48.301999Z","time spent":"314.177872ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:50.486557Z","caller":"traceutil/trace.go:171","msg":"trace[1459647292] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"130.119022ms","start":"2026-02-27T20:28:50.356418Z","end":"2026-02-27T20:28:50.486537Z","steps":["trace[1459647292] 'process raft request' (duration: 129.947118ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:04.022314Z","caller":"traceutil/trace.go:171","msg":"trace[1622846588] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"117.408871ms","start":"2026-02-27T20:29:03.904881Z","end":"2026-02-27T20:29:04.02229Z","steps":["trace[1622846588] 'process raft request' (duration: 117.255717ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:05.30182Z","caller":"traceutil/trace.go:171","msg":"trace[1968799172] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"203.967967ms","start":"2026-02-27T20:29:05.097821Z","end":"2026-02-27T20:29:05.301789Z","steps":["trace[1968799172] 'process raft request' (duration: 106.920977ms)","trace[1968799172] 'compare' (duration: 96.893376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:06.658264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.346183ms","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-27T20:29:06.6584Z","caller":"traceutil/trace.go:171","msg":"trace[544225953] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:908; }","duration":"266.500117ms","start":"2026-02-27T20:29:06.39188Z","end":"2026-02-27T20:29:06.65838Z","steps":["trace[544225953] 'range keys from in-memory index tree' (duration: 266.328252ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:06.658896Z","caller":"traceutil/trace.go:171","msg":"trace[116828905] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"169.702473ms","start":"2026-02-27T20:29:06.48917Z","end":"2026-02-27T20:29:06.658872Z","steps":["trace[116828905] 'read index received' (duration: 169.491368ms)","trace[116828905] 'applied index is now lower than readState.Index' (duration: 210.175µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:06.658995Z","caller":"traceutil/trace.go:171","msg":"trace[1159020973] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"592.6223ms","start":"2026-02-27T20:29:06.066364Z","end":"2026-02-27T20:29:06.658986Z","steps":["trace[1159020973] 'process raft request' (duration: 592.370303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:06.659107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:06.066327Z","time spent":"592.698132ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:06.65949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.307319ms","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-27T20:29:06.659579Z","caller":"traceutil/trace.go:171","msg":"trace[669032954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"170.441003ms","start":"2026-02-27T20:29:06.489118Z","end":"2026-02-27T20:29:06.659559Z","steps":["trace[669032954] 'agreement among raft nodes before linearized reading' (duration: 170.145395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:09.168833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314185121294555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:29:09.669259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314185121294555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:29:10.169796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314185121294555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:29:10.183236Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.118645476s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:29:10.183832Z","caller":"traceutil/trace.go:171","msg":"trace[1197521385] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"2.119324804s","start":"2026-02-27T20:29:08.064479Z","end":"2026-02-27T20:29:10.183804Z","steps":["trace[1197521385] 'process raft request' (duration: 2.119077558s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:10.183978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:08.06446Z","time spent":"2.119430587s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:10.76863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.240845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:10.768749Z","caller":"traceutil/trace.go:171","msg":"trace[268920917] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.44258369s","start":"2026-02-27T20:29:09.326135Z","end":"2026-02-27T20:29:10.768718Z","steps":["trace[268920917] 'process raft request' (duration: 907.174861ms)","trace[268920917] 'compare' (duration: 535.039989ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:10.768784Z","caller":"traceutil/trace.go:171","msg":"trace[63329446] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:951; }","duration":"2.100858992s","start":"2026-02-27T20:29:08.667901Z","end":"2026-02-27T20:29:10.76876Z","steps":["trace[63329446] 'read index received' (duration: 1.51548546s)","trace[63329446] 'applied index is now lower than readState.Index' (duration: 585.372052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:10.768884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:09.326113Z","time spent":"1.442698282s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:11.157304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.56591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:29:11.157421Z","caller":"traceutil/trace.go:171","msg":"trace[882959791] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"388.570941ms","start":"2026-02-27T20:29:10.768828Z","end":"2026-02-27T20:29:11.157399Z","steps":["trace[882959791] 'read index received' (duration: 46.181µs)","trace[882959791] 'applied index is now lower than readState.Index' (duration: 388.52311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:11.157425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.489503575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T20:29:11.157476Z","caller":"traceutil/trace.go:171","msg":"trace[1614215453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"2.489605737s","start":"2026-02-27T20:29:08.667856Z","end":"2026-02-27T20:29:11.157462Z","steps":["trace[1614215453] 'agreement among raft nodes before linearized reading' (duration: 2.101003056s)","trace[1614215453] 'range keys from in-memory index tree' (duration: 388.446648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:11.157502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:08.667834Z","time spent":"2.489663279s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:29:11.15758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395433432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:29:11.157579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417610538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:11.157624Z","caller":"traceutil/trace.go:171","msg":"trace[874504126] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:915; }","duration":"2.395484233s","start":"2026-02-27T20:29:08.762127Z","end":"2026-02-27T20:29:11.157611Z","steps":["trace[874504126] 'agreement among raft nodes before linearized reading' (duration: 2.395396961s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:11.157632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488676013s","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-27T20:29:11.157667Z","caller":"traceutil/trace.go:171","msg":"trace[935550130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"2.488736415s","start":"2026-02-27T20:29:08.668918Z","end":"2026-02-27T20:29:11.157654Z","steps":["trace[935550130] 'agreement among raft nodes before linearized reading' (duration: 2.48853315s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:11.157661Z","caller":"traceutil/trace.go:171","msg":"trace[1771879755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"1.417750342s","start":"2026-02-27T20:29:09.739886Z","end":"2026-02-27T20:29:11.157636Z","steps":["trace[1771879755] 'agreement among raft nodes before linearized reading' (duration: 1.417604978s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:11.157668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:08.762084Z","time spent":"2.395567856s","remote":"127.0.0.1:60226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:29:11.157704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:08.668908Z","time spent":"2.488786906s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:29:11.15768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.388978894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T20:29:11.157721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.386215442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T20:29:11.157707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:09.739863Z","time spent":"1.417836515s","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:29:11.157763Z","caller":"traceutil/trace.go:171","msg":"trace[1818131549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"2.389089977s","start":"2026-02-27T20:29:08.768651Z","end":"2026-02-27T20:29:11.157741Z","steps":["trace[1818131549] 'agreement among raft nodes before linearized reading' (duration: 2.38884373s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:11.157804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:08.768642Z","time spent":"2.389155168s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:29:11.157907Z","caller":"traceutil/trace.go:171","msg":"trace[1928645240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"2.386241183s","start":"2026-02-27T20:29:08.771501Z","end":"2026-02-27T20:29:11.157742Z","steps":["trace[1928645240] 'agreement among raft nodes before linearized reading' (duration: 2.386186021s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:11.157927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:08.77147Z","time spent":"2.386452508s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:29:27.648416Z","caller":"traceutil/trace.go:171","msg":"trace[1981245298] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"257.118695ms","start":"2026-02-27T20:29:27.391274Z","end":"2026-02-27T20:29:27.648393Z","steps":["trace[1981245298] 'read index received' (duration: 256.965711ms)","trace[1981245298] 'applied index is now lower than readState.Index' (duration: 152.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:27.648577Z","caller":"traceutil/trace.go:171","msg":"trace[58970762] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"260.663595ms","start":"2026-02-27T20:29:27.387898Z","end":"2026-02-27T20:29:27.648561Z","steps":["trace[58970762] 'process raft request' (duration: 260.293606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:27.648808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.00024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T20:29:27.648876Z","caller":"traceutil/trace.go:171","msg":"trace[1051073781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"253.104782ms","start":"2026-02-27T20:29:27.395757Z","end":"2026-02-27T20:29:27.648862Z","steps":["trace[1051073781] 'agreement among raft nodes before linearized reading' (duration: 252.932227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:27.648947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.657098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T20:29:27.648993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.725156ms","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-27T20:29:27.649001Z","caller":"traceutil/trace.go:171","msg":"trace[345909795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"257.761191ms","start":"2026-02-27T20:29:27.391224Z","end":"2026-02-27T20:29:27.648985Z","steps":["trace[345909795] 'agreement among raft nodes before linearized reading' (duration: 257.588756ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:27.649033Z","caller":"traceutil/trace.go:171","msg":"trace[885310087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"249.785308ms","start":"2026-02-27T20:29:27.399235Z","end":"2026-02-27T20:29:27.649021Z","steps":["trace[885310087] 'agreement among raft nodes before linearized reading' (duration: 249.674905ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:27.851231Z","caller":"traceutil/trace.go:171","msg":"trace[864084119] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"111.285867ms","start":"2026-02-27T20:29:27.73993Z","end":"2026-02-27T20:29:27.851216Z","steps":["trace[864084119] 'read index received' (duration: 111.108552ms)","trace[864084119] 'applied index is now lower than readState.Index' (duration: 176.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:27.851297Z","caller":"traceutil/trace.go:171","msg":"trace[1528190198] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"197.74173ms","start":"2026-02-27T20:29:27.653535Z","end":"2026-02-27T20:29:27.851277Z","steps":["trace[1528190198] 'process raft request' (duration: 197.555466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:27.851409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.397019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:27.851443Z","caller":"traceutil/trace.go:171","msg":"trace[247305564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"111.551043ms","start":"2026-02-27T20:29:27.739884Z","end":"2026-02-27T20:29:27.851435Z","steps":["trace[247305564] 'agreement among raft nodes before linearized reading' (duration: 111.39673ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:28.919356Z","caller":"traceutil/trace.go:171","msg":"trace[1010023178] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"179.209958ms","start":"2026-02-27T20:29:28.74012Z","end":"2026-02-27T20:29:28.91933Z","steps":["trace[1010023178] 'read index received' (duration: 178.949031ms)","trace[1010023178] 'applied index is now lower than readState.Index' (duration: 259.487µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:28.919399Z","caller":"traceutil/trace.go:171","msg":"trace[1660673295] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"574.416242ms","start":"2026-02-27T20:29:28.344951Z","end":"2026-02-27T20:29:28.919367Z","steps":["trace[1660673295] 'process raft request' (duration: 574.146405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:28.919477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.346182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:28.919512Z","caller":"traceutil/trace.go:171","msg":"trace[1927040112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"179.471955ms","start":"2026-02-27T20:29:28.740031Z","end":"2026-02-27T20:29:28.919503Z","steps":["trace[1927040112] 'agreement among raft nodes before linearized reading' (duration: 179.395484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:28.919566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:28.34494Z","time spent":"574.542256ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:29.93045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.941503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-27T20:29:29.930588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.164635ms","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-27T20:29:29.930655Z","caller":"traceutil/trace.go:171","msg":"trace[1528577514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"255.288018ms","start":"2026-02-27T20:29:29.67535Z","end":"2026-02-27T20:29:29.930638Z","steps":["trace[1528577514] 'range keys from in-memory index tree' (duration: 254.98081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:29.930787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.06086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:29:29.930858Z","caller":"traceutil/trace.go:171","msg":"trace[612062056] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:977; }","duration":"903.159922ms","start":"2026-02-27T20:29:29.027681Z","end":"2026-02-27T20:29:29.930841Z","steps":["trace[612062056] 'count revisions from in-memory index tree' (duration: 902.931087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:29.930919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:29.027669Z","time spent":"903.236075ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:29:29.93045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.56727ms","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-27T20:29:29.93099Z","caller":"traceutil/trace.go:171","msg":"trace[162756573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"277.161735ms","start":"2026-02-27T20:29:29.653819Z","end":"2026-02-27T20:29:29.930981Z","steps":["trace[162756573] 'range keys from in-memory index tree' (duration: 276.427557ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:29.930584Z","caller":"traceutil/trace.go:171","msg":"trace[167047887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"254.127208ms","start":"2026-02-27T20:29:29.676409Z","end":"2026-02-27T20:29:29.930537Z","steps":["trace[167047887] 'range keys from in-memory index tree' (duration: 253.770679ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:29.97629Z","caller":"traceutil/trace.go:171","msg":"trace[1200407910] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"164.852952ms","start":"2026-02-27T20:29:29.811415Z","end":"2026-02-27T20:29:29.976268Z","steps":["trace[1200407910] 'process raft request' (duration: 164.748469ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:29.97638Z","caller":"traceutil/trace.go:171","msg":"trace[1936010900] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1020; }","duration":"121.499927ms","start":"2026-02-27T20:29:29.854854Z","end":"2026-02-27T20:29:29.976354Z","steps":["trace[1936010900] 'read index received' (duration: 121.484627ms)","trace[1936010900] 'applied index is now lower than readState.Index' (duration: 9.61µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:29.976581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.715802ms","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-27T20:29:29.976617Z","caller":"traceutil/trace.go:171","msg":"trace[328734393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"121.806515ms","start":"2026-02-27T20:29:29.8548Z","end":"2026-02-27T20:29:29.976607Z","steps":["trace[328734393] 'agreement among raft nodes before linearized reading' (duration: 121.644541ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:30.24461Z","caller":"traceutil/trace.go:171","msg":"trace[1690507901] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"310.327431ms","start":"2026-02-27T20:29:29.934257Z","end":"2026-02-27T20:29:30.244584Z","steps":["trace[1690507901] 'process raft request' (duration: 293.873731ms)","trace[1690507901] 'compare' (duration: 16.187833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:30.244758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:29.934235Z","time spent":"310.446953ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:30.244866Z","caller":"traceutil/trace.go:171","msg":"trace[1218435637] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"310.046813ms","start":"2026-02-27T20:29:29.934793Z","end":"2026-02-27T20:29:30.24484Z","steps":["trace[1218435637] 'process raft request' (duration: 309.632853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:30.245024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:29.934781Z","time spent":"310.160496ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:30.245446Z","caller":"traceutil/trace.go:171","msg":"trace[641971025] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"264.857901ms","start":"2026-02-27T20:29:29.980575Z","end":"2026-02-27T20:29:30.245433Z","steps":["trace[641971025] 'process raft request' (duration: 264.756279ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:30.245532Z","caller":"traceutil/trace.go:171","msg":"trace[1222087319] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"310.268508ms","start":"2026-02-27T20:29:29.93525Z","end":"2026-02-27T20:29:30.245519Z","steps":["trace[1222087319] 'process raft request' (duration: 309.215722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:30.245604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:29.935228Z","time spent":"310.340631ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:30.495287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.089119ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:29:30.495426Z","caller":"traceutil/trace.go:171","msg":"trace[1606493883] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"249.997532ms","start":"2026-02-27T20:29:30.245405Z","end":"2026-02-27T20:29:30.495402Z","steps":["trace[1606493883] 'read index received' (duration: 31.77913ms)","trace[1606493883] 'applied index is now lower than readState.Index' (duration: 218.216192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:30.495533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.363634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:30.495568Z","caller":"traceutil/trace.go:171","msg":"trace[1427178925] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:982; }","duration":"254.430265ms","start":"2026-02-27T20:29:30.241128Z","end":"2026-02-27T20:29:30.495558Z","steps":["trace[1427178925] 'agreement among raft nodes before linearized reading' (duration: 254.346133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:31.316747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.665805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:29:31.316906Z","caller":"traceutil/trace.go:171","msg":"trace[1181645376] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:983; }","duration":"109.85511ms","start":"2026-02-27T20:29:31.207031Z","end":"2026-02-27T20:29:31.316886Z","steps":["trace[1181645376] 'count revisions from in-memory index tree' (duration: 109.447689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:31.317143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.570598ms","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-27T20:29:31.317207Z","caller":"traceutil/trace.go:171","msg":"trace[1111389586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"394.677621ms","start":"2026-02-27T20:29:30.922514Z","end":"2026-02-27T20:29:31.317192Z","steps":["trace[1111389586] 'range keys from in-memory index tree' (duration: 394.306151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:31.317239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:30.922493Z","time spent":"394.739123ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:29:31.317356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.597868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:31.317444Z","caller":"traceutil/trace.go:171","msg":"trace[1718747001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"576.856134ms","start":"2026-02-27T20:29:30.740567Z","end":"2026-02-27T20:29:31.317423Z","steps":["trace[1718747001] 'range keys from in-memory index tree' (duration: 576.531156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:31.317497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:30.740549Z","time spent":"576.935296ms","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:29:31.402002Z","caller":"traceutil/trace.go:171","msg":"trace[973225788] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"181.595039ms","start":"2026-02-27T20:29:31.220387Z","end":"2026-02-27T20:29:31.401982Z","steps":["trace[973225788] 'process raft request' (duration: 181.466496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:32.691252Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.289214333s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:29:32.69158Z","caller":"traceutil/trace.go:171","msg":"trace[741800383] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"443.340311ms","start":"2026-02-27T20:29:32.24822Z","end":"2026-02-27T20:29:32.69156Z","steps":["trace[741800383] 'read index received' (duration: 443.151896ms)","trace[741800383] 'applied index is now lower than readState.Index' (duration: 187.755µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:32.691687Z","caller":"traceutil/trace.go:171","msg":"trace[510164391] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.370930605s","start":"2026-02-27T20:29:31.320724Z","end":"2026-02-27T20:29:32.691655Z","steps":["trace[510164391] 'process raft request' (duration: 1.37072084s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:32.691771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.530536ms","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-27T20:29:32.691793Z","caller":"traceutil/trace.go:171","msg":"trace[327725160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"443.609797ms","start":"2026-02-27T20:29:32.248177Z","end":"2026-02-27T20:29:32.691787Z","steps":["trace[327725160] 'agreement among raft nodes before linearized reading' (duration: 443.451513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:32.691786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.496885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-27T20:29:32.691815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:32.248159Z","time spent":"443.650269ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:29:32.691825Z","caller":"traceutil/trace.go:171","msg":"trace[1327960915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"443.552296ms","start":"2026-02-27T20:29:32.24826Z","end":"2026-02-27T20:29:32.691812Z","steps":["trace[1327960915] 'agreement among raft nodes before linearized reading' (duration: 443.412523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:32.691847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:31.320715Z","time spent":"1.371057529s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:32.691856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:32.248255Z","time spent":"443.593417ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:29:32.691981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.871739ms","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-27T20:29:32.69201Z","caller":"traceutil/trace.go:171","msg":"trace[584008529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"442.924821ms","start":"2026-02-27T20:29:32.249077Z","end":"2026-02-27T20:29:32.692002Z","steps":["trace[584008529] 'agreement among raft nodes before linearized reading' (duration: 442.850339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:32.692035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:32.249069Z","time spent":"442.959952ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:29:32.69212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.664529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-27T20:29:32.692136Z","caller":"traceutil/trace.go:171","msg":"trace[977417727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"443.69675ms","start":"2026-02-27T20:29:32.248435Z","end":"2026-02-27T20:29:32.692132Z","steps":["trace[977417727] 'agreement among raft nodes before linearized reading' (duration: 443.645849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:32.692149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:32.248429Z","time spent":"443.717411ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:29:33.333651Z","caller":"traceutil/trace.go:171","msg":"trace[1826654337] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"161.538648ms","start":"2026-02-27T20:29:33.172093Z","end":"2026-02-27T20:29:33.333632Z","steps":["trace[1826654337] 'process raft request' (duration: 161.386984ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:33.608158Z","caller":"traceutil/trace.go:171","msg":"trace[1794909979] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"268.72088ms","start":"2026-02-27T20:29:33.33941Z","end":"2026-02-27T20:29:33.608131Z","steps":["trace[1794909979] 'process raft request' (duration: 223.695552ms)","trace[1794909979] 'compare' (duration: 44.921095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:34.216287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.516647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:34.216369Z","caller":"traceutil/trace.go:171","msg":"trace[1865029630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"476.66089ms","start":"2026-02-27T20:29:33.739689Z","end":"2026-02-27T20:29:34.21635Z","steps":["trace[1865029630] 'range keys from in-memory index tree' (duration: 476.462175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:34.216423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:33.739676Z","time spent":"476.738093ms","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:29:35.109467Z","caller":"traceutil/trace.go:171","msg":"trace[218346943] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"410.778672ms","start":"2026-02-27T20:29:34.698663Z","end":"2026-02-27T20:29:35.109442Z","steps":["trace[218346943] 'process raft request' (duration: 410.583007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.109638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:34.698641Z","time spent":"410.912444ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:35.109794Z","caller":"traceutil/trace.go:171","msg":"trace[829411170] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"369.405886ms","start":"2026-02-27T20:29:34.739974Z","end":"2026-02-27T20:29:35.10938Z","steps":["trace[829411170] 'read index received' (duration: 369.211591ms)","trace[829411170] 'applied index is now lower than readState.Index' (duration: 193.335µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:35.109985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.961421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:35.110075Z","caller":"traceutil/trace.go:171","msg":"trace[1528551961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"370.134435ms","start":"2026-02-27T20:29:34.739922Z","end":"2026-02-27T20:29:35.110056Z","steps":["trace[1528551961] 'agreement among raft nodes before linearized reading' (duration: 369.9277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.110135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:34.739904Z","time spent":"370.211437ms","remote":"127.0.0.1:59866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:29:35.110019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.94953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:29:35.110324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.387884ms","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-27T20:29:35.110518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.23265ms","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-27T20:29:35.110608Z","caller":"traceutil/trace.go:171","msg":"trace[1783454412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"325.383374ms","start":"2026-02-27T20:29:34.785202Z","end":"2026-02-27T20:29:35.110586Z","steps":["trace[1783454412] 'agreement among raft nodes before linearized reading' (duration: 325.114247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.110653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:34.785154Z","time spent":"325.491267ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:29:35.110405Z","caller":"traceutil/trace.go:171","msg":"trace[1841427912] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:993; }","duration":"215.37459ms","start":"2026-02-27T20:29:34.895013Z","end":"2026-02-27T20:29:35.110387Z","steps":["trace[1841427912] 'agreement among raft nodes before linearized reading' (duration: 214.957749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.111081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.249716ms","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-27T20:29:35.111136Z","caller":"traceutil/trace.go:171","msg":"trace[114518477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"326.328258ms","start":"2026-02-27T20:29:34.784792Z","end":"2026-02-27T20:29:35.11112Z","steps":["trace[114518477] 'agreement among raft nodes before linearized reading' (duration: 326.138284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.111176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:34.784781Z","time spent":"326.38792ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:29:35.110437Z","caller":"traceutil/trace.go:171","msg":"trace[971033245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"325.570199ms","start":"2026-02-27T20:29:34.784841Z","end":"2026-02-27T20:29:35.110411Z","steps":["trace[971033245] 'agreement among raft nodes before linearized reading' (duration: 325.22074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.111475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:34.784834Z","time spent":"326.625446ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:29:35.111976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.29536ms","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-27T20:29:35.112025Z","caller":"traceutil/trace.go:171","msg":"trace[862932267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"352.442254ms","start":"2026-02-27T20:29:34.759567Z","end":"2026-02-27T20:29:35.112009Z","steps":["trace[862932267] 'agreement among raft nodes before linearized reading' (duration: 350.349151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.112476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:34.759555Z","time spent":"352.900115ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:29:35.351624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.894331ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-27T20:29:35.576486Z","caller":"traceutil/trace.go:171","msg":"trace[1234270879] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"461.596795ms","start":"2026-02-27T20:29:35.114864Z","end":"2026-02-27T20:29:35.57646Z","steps":["trace[1234270879] 'process raft request' (duration: 461.368779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.576908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.114846Z","time spent":"461.918954ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:35.577102Z","caller":"traceutil/trace.go:171","msg":"trace[1289693421] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"460.900747ms","start":"2026-02-27T20:29:35.116151Z","end":"2026-02-27T20:29:35.577051Z","steps":["trace[1289693421] 'process raft request' (duration: 460.254001ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:35.577279Z","caller":"traceutil/trace.go:171","msg":"trace[645185758] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"459.712828ms","start":"2026-02-27T20:29:35.117554Z","end":"2026-02-27T20:29:35.577266Z","steps":["trace[645185758] 'process raft request' (duration: 459.585425ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:35.577251Z","caller":"traceutil/trace.go:171","msg":"trace[1059078796] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"460.539709ms","start":"2026-02-27T20:29:35.116653Z","end":"2026-02-27T20:29:35.577193Z","steps":["trace[1059078796] 'process raft request' (duration: 460.255742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:35.577302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.116138Z","time spent":"461.059142ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:35.577387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.117542Z","time spent":"459.7887ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:35.577464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.116642Z","time spent":"460.729704ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:36.439678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.111687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:36.43984Z","caller":"traceutil/trace.go:171","msg":"trace[812407177] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"701.41539ms","start":"2026-02-27T20:29:35.738401Z","end":"2026-02-27T20:29:36.439816Z","steps":["trace[812407177] 'read index received' (duration: 84.922µs)","trace[812407177] 'applied index is now lower than readState.Index' (duration: 701.328468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:36.439932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.535943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:36.439956Z","caller":"traceutil/trace.go:171","msg":"trace[1486566841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"701.597224ms","start":"2026-02-27T20:29:35.738351Z","end":"2026-02-27T20:29:36.439948Z","steps":["trace[1486566841] 'agreement among raft nodes before linearized reading' (duration: 701.520392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:36.439987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.738322Z","time spent":"701.658536ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:29:36.440175Z","caller":"traceutil/trace.go:171","msg":"trace[1769439991] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.087112182s","start":"2026-02-27T20:29:35.353051Z","end":"2026-02-27T20:29:36.440163Z","steps":["trace[1769439991] 'process raft request' (duration: 355.424491ms)","trace[1769439991] 'compare' (duration: 730.517621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:36.44023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.35303Z","time spent":"1.087168693s","remote":"127.0.0.1:59908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:42.114004Z","caller":"traceutil/trace.go:171","msg":"trace[1324265963] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"148.725211ms","start":"2026-02-27T20:29:41.965251Z","end":"2026-02-27T20:29:42.113976Z","steps":["trace[1324265963] 'process raft request' (duration: 130.605449ms)","trace[1324265963] 'compare' (duration: 18.005389ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:18.673389Z","caller":"traceutil/trace.go:171","msg":"trace[1317901411] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"102.836251ms","start":"2026-02-27T20:30:18.570535Z","end":"2026-02-27T20:30:18.673372Z","steps":["trace[1317901411] 'process raft request' (duration: 102.723679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:51.314079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.185032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:51.314179Z","caller":"traceutil/trace.go:171","msg":"trace[1704863892] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1369; }","duration":"103.361496ms","start":"2026-02-27T20:30:51.210797Z","end":"2026-02-27T20:30:51.314159Z","steps":["trace[1704863892] 'count revisions from in-memory index tree' (duration: 103.044508ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:28.682336Z","caller":"traceutil/trace.go:171","msg":"trace[658686886] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"177.138725ms","start":"2026-02-27T20:31:28.505168Z","end":"2026-02-27T20:31:28.682307Z","steps":["trace[658686886] 'process raft request' (duration: 105.34182ms)","trace[658686886] 'compare' (duration: 71.535638ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:28.682442Z","caller":"traceutil/trace.go:171","msg":"trace[1670695378] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"176.786846ms","start":"2026-02-27T20:31:28.505635Z","end":"2026-02-27T20:31:28.682422Z","steps":["trace[1670695378] 'process raft request' (duration: 176.595521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:32:00.625783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.030886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:32:00.847351Z","caller":"traceutil/trace.go:171","msg":"trace[1450235237] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"143.435583ms","start":"2026-02-27T20:32:00.703885Z","end":"2026-02-27T20:32:00.847321Z","steps":["trace[1450235237] 'process raft request' (duration: 87.166495ms)","trace[1450235237] 'compare' (duration: 56.078313ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:32:00.84755Z","caller":"traceutil/trace.go:171","msg":"trace[1595694025] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"142.278553ms","start":"2026-02-27T20:32:00.705261Z","end":"2026-02-27T20:32:00.847539Z","steps":["trace[1595694025] 'process raft request' (duration: 142.009356ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:00.847579Z","caller":"traceutil/trace.go:171","msg":"trace[2064818355] linearizableReadLoop","detail":"{readStateIndex:1761; appliedIndex:1757; }","duration":"108.531042ms","start":"2026-02-27T20:32:00.739031Z","end":"2026-02-27T20:32:00.847562Z","steps":["trace[2064818355] 'read index received' (duration: 52.031038ms)","trace[2064818355] 'applied index is now lower than readState.Index' (duration: 56.499274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:32:00.847762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.688697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:32:00.84784Z","caller":"traceutil/trace.go:171","msg":"trace[896068167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1687; }","duration":"108.83281ms","start":"2026-02-27T20:32:00.738987Z","end":"2026-02-27T20:32:00.84782Z","steps":["trace[896068167] 'agreement among raft nodes before linearized reading' (duration: 108.689096ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:00.84785Z","caller":"traceutil/trace.go:171","msg":"trace[632799437] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"142.566621ms","start":"2026-02-27T20:32:00.705268Z","end":"2026-02-27T20:32:00.847835Z","steps":["trace[632799437] 'process raft request' (duration: 142.194411ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:00.847906Z","caller":"traceutil/trace.go:171","msg":"trace[28034393] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"137.431417ms","start":"2026-02-27T20:32:00.710451Z","end":"2026-02-27T20:32:00.847882Z","steps":["trace[28034393] 'process raft request' (duration: 137.083818ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:38.343041Z","caller":"traceutil/trace.go:171","msg":"trace[1177718996] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"138.933887ms","start":"2026-02-27T20:32:38.204058Z","end":"2026-02-27T20:32:38.342992Z","steps":["trace[1177718996] 'process raft request' (duration: 138.083114ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:46.099267Z","caller":"traceutil/trace.go:171","msg":"trace[24949782] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"104.414481ms","start":"2026-02-27T20:32:45.994833Z","end":"2026-02-27T20:32:46.099248Z","steps":["trace[24949782] 'process raft request' (duration: 104.359259ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:46.09948Z","caller":"traceutil/trace.go:171","msg":"trace[1588581265] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"105.13392ms","start":"2026-02-27T20:32:45.994335Z","end":"2026-02-27T20:32:46.099469Z","steps":["trace[1588581265] 'process raft request' (duration: 55.541652ms)","trace[1588581265] 'compare' (duration: 49.038353ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:32:46.835681Z","caller":"traceutil/trace.go:171","msg":"trace[794017848] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2009; }","duration":"405.766084ms","start":"2026-02-27T20:32:46.429889Z","end":"2026-02-27T20:32:46.835655Z","steps":["trace[794017848] 'read index received' (duration: 405.341932ms)","trace[794017848] 'applied index is now lower than readState.Index' (duration: 422.972µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:32:46.836127Z","caller":"traceutil/trace.go:171","msg":"trace[1413978212] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"458.40408ms","start":"2026-02-27T20:32:46.377707Z","end":"2026-02-27T20:32:46.836111Z","steps":["trace[1413978212] 'process raft request' (duration: 457.703842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:32:46.836431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:32:46.377677Z","time spent":"458.573075ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:32:46.836577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.650206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-27T20:32:46.836679Z","caller":"traceutil/trace.go:171","msg":"trace[1020452956] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1926; }","duration":"406.815601ms","start":"2026-02-27T20:32:46.429838Z","end":"2026-02-27T20:32:46.836653Z","steps":["trace[1020452956] 'agreement among raft nodes before linearized reading' (duration: 406.537663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:32:46.836737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:32:46.429815Z","time spent":"406.912354ms","remote":"127.0.0.1:59988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "}