{"level":"warn","ts":"2026-02-21T17:09:39.070443Z","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-21T17:09:39.070705Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18: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.19.213.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18: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-21T17:09:39.070919Z","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-21T17:09:39.07098Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-02-21T17:09:39.071043Z","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-21T17:09:39.07231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-02-21T17:09:39.072493Z","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.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18: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-21T17:09:39.076506Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.624966ms"} {"level":"info","ts":"2026-02-21T17:09:39.082745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-02-21T17:09:39.082852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-02-21T17:09:39.082904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-02-21T17:09:39.082929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-21T17:09:39.082959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-02-21T17:09:39.083009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-02-21T17:09:39.088748Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-21T17:09:39.090202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-21T17:09:39.091392Z","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-21T17:09:39.094276Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-21T17:09:39.094822Z","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-21T17:09:39.094961Z","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-21T17:09:39.095001Z","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-21T17:09:39.095271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-21T17:09:39.095646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-02-21T17:09:39.095816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-02-21T17:09:39.100861Z","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-21T17:09:39.101053Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-02-21T17:09:39.101125Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-02-21T17:09:39.101321Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-21T17:09:39.101386Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-21T17:09:39.58452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-02-21T17:09:39.584643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-21T17:09:39.584727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-02-21T17:09:39.584787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-02-21T17:09:39.584824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-02-21T17:09:39.584865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-02-21T17:09:39.58491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-02-21T17:09:39.586405Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-21T17:09:39.586493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T17:09:39.586623Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T17:09:39.586568Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T17:09:39.587879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T17:09:39.58833Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-21T17:09:39.588372Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-21T17:09:39.588926Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T17:09:39.589051Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T17:09:39.589887Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"info","ts":"2026-02-21T17:09:39.591742Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-21T17:09:45.916941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.23752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:09:45.917088Z","caller":"traceutil/trace.go:171","msg":"trace[618610484] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:316; }","duration":"174.520866ms","start":"2026-02-21T17:09:45.742539Z","end":"2026-02-21T17:09:45.91706Z","steps":["trace[618610484] 'range keys from in-memory index tree' (duration: 174.106986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:49.427492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.8903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:09:49.427706Z","caller":"traceutil/trace.go:171","msg":"trace[610931632] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"293.227916ms","start":"2026-02-21T17:09:49.134453Z","end":"2026-02-21T17:09:49.427681Z","steps":["trace[610931632] 'process raft request' (duration: 177.423055ms)","trace[610931632] 'compare' (duration: 114.621234ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:09:49.619539Z","caller":"traceutil/trace.go:171","msg":"trace[782448295] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"484.060884ms","start":"2026-02-21T17:09:49.135441Z","end":"2026-02-21T17:09:49.619502Z","steps":["trace[782448295] 'process raft request' (duration: 481.148485ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:09:49.619711Z","caller":"traceutil/trace.go:171","msg":"trace[1757722644] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:360; }","duration":"476.81302ms","start":"2026-02-21T17:09:49.142852Z","end":"2026-02-21T17:09:49.619665Z","steps":["trace[1757722644] 'read index received' (duration: 169.179258ms)","trace[1757722644] 'applied index is now lower than readState.Index' (duration: 307.632102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:09:49.619749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:49.135426Z","time spent":"484.212678ms","remote":"127.0.0.1:49758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:09:49.620013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.129187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-21T17:09:49.620124Z","caller":"traceutil/trace.go:171","msg":"trace[1250211150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:353; }","duration":"477.280521ms","start":"2026-02-21T17:09:49.142812Z","end":"2026-02-21T17:09:49.620092Z","steps":["trace[1250211150] 'agreement among raft nodes before linearized reading' (duration: 477.097587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:49.62017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:49.142796Z","time spent":"477.365314ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-21T17:09:49.620201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.450515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-21T17:09:49.620249Z","caller":"traceutil/trace.go:171","msg":"trace[1379747669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:353; }","duration":"426.561448ms","start":"2026-02-21T17:09:49.193674Z","end":"2026-02-21T17:09:49.620236Z","steps":["trace[1379747669] 'agreement among raft nodes before linearized reading' (duration: 426.455635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:49.620287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:49.193645Z","time spent":"426.63382ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-21T17:09:49.620299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.636004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-21T17:09:49.620347Z","caller":"traceutil/trace.go:171","msg":"trace[575496953] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:353; }","duration":"226.740716ms","start":"2026-02-21T17:09:49.393592Z","end":"2026-02-21T17:09:49.620333Z","steps":["trace[575496953] 'agreement among raft nodes before linearized reading' (duration: 226.647284ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:09:49.620392Z","caller":"traceutil/trace.go:171","msg":"trace[2045948768] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"483.509612ms","start":"2026-02-21T17:09:49.136865Z","end":"2026-02-21T17:09:49.620375Z","steps":["trace[2045948768] 'process raft request' (duration: 482.485008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:49.620498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:49.136843Z","time spent":"483.582363ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4000,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:09:49.620512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.50427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-21T17:09:49.62053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.480066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-21T17:09:49.620546Z","caller":"traceutil/trace.go:171","msg":"trace[358967244] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:353; }","duration":"376.583372ms","start":"2026-02-21T17:09:49.243953Z","end":"2026-02-21T17:09:49.620537Z","steps":["trace[358967244] 'agreement among raft nodes before linearized reading' (duration: 376.52914ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:09:49.620565Z","caller":"traceutil/trace.go:171","msg":"trace[1342452645] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:353; }","duration":"276.512777ms","start":"2026-02-21T17:09:49.34404Z","end":"2026-02-21T17:09:49.620553Z","steps":["trace[1342452645] 'agreement among raft nodes before linearized reading' (duration: 276.450386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:49.620577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:49.243924Z","time spent":"376.647303ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-21T17:09:49.620562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.418265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-21T17:09:49.620652Z","caller":"traceutil/trace.go:171","msg":"trace[339730178] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:353; }","duration":"327.569969ms","start":"2026-02-21T17:09:49.293061Z","end":"2026-02-21T17:09:49.620631Z","steps":["trace[339730178] 'agreement among raft nodes before linearized reading' (duration: 327.383704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:49.620699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:49.293035Z","time spent":"327.654891ms","remote":"127.0.0.1:49550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-21T17:09:51.617418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993700545800998495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-21T17:09:51.913135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.065671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4014"} {"level":"info","ts":"2026-02-21T17:09:51.913047Z","caller":"traceutil/trace.go:171","msg":"trace[592572000] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"803.417337ms","start":"2026-02-21T17:09:51.109595Z","end":"2026-02-21T17:09:51.913013Z","steps":["trace[592572000] 'process raft request' (duration: 736.364453ms)","trace[592572000] 'compare' (duration: 66.532221ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:09:51.913197Z","caller":"traceutil/trace.go:171","msg":"trace[790772383] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:392; }","duration":"796.182505ms","start":"2026-02-21T17:09:51.117Z","end":"2026-02-21T17:09:51.913182Z","steps":["trace[790772383] 'agreement among raft nodes before linearized reading' (duration: 796.060151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:51.913227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:51.11698Z","time spent":"796.243447ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4037,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-21T17:09:51.91353Z","caller":"traceutil/trace.go:171","msg":"trace[2126302650] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"795.887347ms","start":"2026-02-21T17:09:51.117038Z","end":"2026-02-21T17:09:51.912925Z","steps":["trace[2126302650] 'read index received' (duration: 728.846513ms)","trace[2126302650] 'applied index is now lower than readState.Index' (duration: 67.039393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:09:51.913806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:51.109582Z","time spent":"803.96047ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:09:51.914195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.86289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-4n9rt\" ","response":"range_response_count:1 size:4417"} {"level":"info","ts":"2026-02-21T17:09:51.91438Z","caller":"traceutil/trace.go:171","msg":"trace[1564289603] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-4n9rt; range_end:; response_count:1; response_revision:392; }","duration":"362.077474ms","start":"2026-02-21T17:09:51.552282Z","end":"2026-02-21T17:09:51.914359Z","steps":["trace[1564289603] 'agreement among raft nodes before linearized reading' (duration: 361.803188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:51.914522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:51.552265Z","time spent":"362.238699ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4440,"request content":"key:\"/registry/pods/kube-system/kube-proxy-4n9rt\" "} {"level":"info","ts":"2026-02-21T17:09:52.351604Z","caller":"traceutil/trace.go:171","msg":"trace[973612960] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"425.934883ms","start":"2026-02-21T17:09:51.925623Z","end":"2026-02-21T17:09:52.351558Z","steps":["trace[973612960] 'process raft request' (duration: 411.907357ms)","trace[973612960] 'compare' (duration: 13.862952ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:09:52.352373Z","caller":"traceutil/trace.go:171","msg":"trace[1829239246] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"165.852349ms","start":"2026-02-21T17:09:52.186472Z","end":"2026-02-21T17:09:52.352324Z","steps":["trace[1829239246] 'read index received' (duration: 151.123837ms)","trace[1829239246] 'applied index is now lower than readState.Index' (duration: 14.725802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:09:52.352414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:09:51.925607Z","time spent":"426.078957ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4396,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:09:52.35253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.057154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-21T17:09:52.352571Z","caller":"traceutil/trace.go:171","msg":"trace[426319415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"166.136286ms","start":"2026-02-21T17:09:52.186423Z","end":"2026-02-21T17:09:52.352559Z","steps":["trace[426319415] 'agreement among raft nodes before linearized reading' (duration: 166.038753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:52.352815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.867322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-21T17:09:52.352899Z","caller":"traceutil/trace.go:171","msg":"trace[547425205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"159.014695ms","start":"2026-02-21T17:09:52.193865Z","end":"2026-02-21T17:09:52.35288Z","steps":["trace[547425205] 'agreement among raft nodes before linearized reading' (duration: 158.848801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:52.352964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.978998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-21T17:09:52.353021Z","caller":"traceutil/trace.go:171","msg":"trace[501761597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"142.081341ms","start":"2026-02-21T17:09:52.210921Z","end":"2026-02-21T17:09:52.353002Z","steps":["trace[501761597] 'agreement among raft nodes before linearized reading' (duration: 141.982178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:56.590058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.993757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:09:56.590315Z","caller":"traceutil/trace.go:171","msg":"trace[2104318227] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"157.227812ms","start":"2026-02-21T17:09:56.433064Z","end":"2026-02-21T17:09:56.590292Z","steps":["trace[2104318227] 'read index received' (duration: 2.885269ms)","trace[2104318227] 'applied index is now lower than readState.Index' (duration: 154.341193ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:09:56.590394Z","caller":"traceutil/trace.go:171","msg":"trace[111057439] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"157.990681ms","start":"2026-02-21T17:09:56.432384Z","end":"2026-02-21T17:09:56.590375Z","steps":["trace[111057439] 'process raft request' (duration: 28.586114ms)","trace[111057439] 'compare' (duration: 128.838014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:09:56.590438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.368816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-21T17:09:56.59046Z","caller":"traceutil/trace.go:171","msg":"trace[845957424] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"155.911321ms","start":"2026-02-21T17:09:56.434523Z","end":"2026-02-21T17:09:56.590435Z","steps":["trace[845957424] 'process raft request' (duration: 155.688316ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:09:56.590491Z","caller":"traceutil/trace.go:171","msg":"trace[1534911444] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:413; }","duration":"157.445827ms","start":"2026-02-21T17:09:56.43303Z","end":"2026-02-21T17:09:56.590476Z","steps":["trace[1534911444] 'agreement among raft nodes before linearized reading' (duration: 157.345155ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:09:56.77062Z","caller":"traceutil/trace.go:171","msg":"trace[1872066271] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"111.96402ms","start":"2026-02-21T17:09:56.658616Z","end":"2026-02-21T17:09:56.77058Z","steps":["trace[1872066271] 'read index received' (duration: 33.3995ms)","trace[1872066271] 'applied index is now lower than readState.Index' (duration: 78.56319ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:09:56.770699Z","caller":"traceutil/trace.go:171","msg":"trace[316595070] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"172.631291ms","start":"2026-02-21T17:09:56.598033Z","end":"2026-02-21T17:09:56.770664Z","steps":["trace[316595070] 'process raft request' (duration: 94.01831ms)","trace[316595070] 'compare' (duration: 78.343815ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:09:56.770839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.209997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4014"} {"level":"info","ts":"2026-02-21T17:09:56.770916Z","caller":"traceutil/trace.go:171","msg":"trace[907997205] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"112.34067ms","start":"2026-02-21T17:09:56.658557Z","end":"2026-02-21T17:09:56.770898Z","steps":["trace[907997205] 'agreement among raft nodes before linearized reading' (duration: 112.181796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:57.234484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.690061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:09:57.234583Z","caller":"traceutil/trace.go:171","msg":"trace[1250206928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"136.819284ms","start":"2026-02-21T17:09:57.097741Z","end":"2026-02-21T17:09:57.23456Z","steps":["trace[1250206928] 'range keys from in-memory index tree' (duration: 136.588529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:57.234927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.85154ms","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-21T17:09:57.234963Z","caller":"traceutil/trace.go:171","msg":"trace[238003905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"127.925021ms","start":"2026-02-21T17:09:57.107027Z","end":"2026-02-21T17:09:57.234952Z","steps":["trace[238003905] 'range keys from in-memory index tree' (duration: 127.231605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:09:57.876372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.084134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T17:10:04.122107Z","caller":"traceutil/trace.go:171","msg":"trace[93394035] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"793.426556ms","start":"2026-02-21T17:10:03.328657Z","end":"2026-02-21T17:10:04.122084Z","steps":["trace[93394035] 'process raft request' (duration: 793.272642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:04.122305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:03.328632Z","time spent":"793.5814ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:10:05.025456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.587887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:10:05.025721Z","caller":"traceutil/trace.go:171","msg":"trace[216307818] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"395.511039ms","start":"2026-02-21T17:10:04.63019Z","end":"2026-02-21T17:10:05.025701Z","steps":["trace[216307818] 'process raft request' (duration: 62.501542ms)","trace[216307818] 'compare' (duration: 332.215239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:05.025778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:04.63018Z","time spent":"395.571821ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:05.026307Z","caller":"traceutil/trace.go:171","msg":"trace[834133187] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"374.567358ms","start":"2026-02-21T17:10:04.651702Z","end":"2026-02-21T17:10:05.02627Z","steps":["trace[834133187] 'process raft request' (duration: 373.898383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:05.026521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:04.651695Z","time spent":"374.700362ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:12.660717Z","caller":"traceutil/trace.go:171","msg":"trace[1080610206] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"157.882237ms","start":"2026-02-21T17:10:12.502806Z","end":"2026-02-21T17:10:12.660688Z","steps":["trace[1080610206] 'read index received' (duration: 157.767674ms)","trace[1080610206] 'applied index is now lower than readState.Index' (duration: 112.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:10:12.660798Z","caller":"traceutil/trace.go:171","msg":"trace[59328973] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"170.653975ms","start":"2026-02-21T17:10:12.490091Z","end":"2026-02-21T17:10:12.660745Z","steps":["trace[59328973] 'process raft request' (duration: 170.407599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:12.66096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.122443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-21T17:10:12.661078Z","caller":"traceutil/trace.go:171","msg":"trace[1538990898] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:512; }","duration":"158.300647ms","start":"2026-02-21T17:10:12.50275Z","end":"2026-02-21T17:10:12.661051Z","steps":["trace[1538990898] 'agreement among raft nodes before linearized reading' (duration: 158.065852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:13.05678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:12.662225Z","time spent":"394.550577ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-21T17:10:13.062509Z","caller":"traceutil/trace.go:171","msg":"trace[153204266] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"396.740878ms","start":"2026-02-21T17:10:12.665748Z","end":"2026-02-21T17:10:13.062489Z","steps":["trace[153204266] 'process raft request' (duration: 396.606745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:13.062612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:12.665721Z","time spent":"396.83464ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:15.328627Z","caller":"traceutil/trace.go:171","msg":"trace[1014511895] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"257.5811ms","start":"2026-02-21T17:10:15.071009Z","end":"2026-02-21T17:10:15.32859Z","steps":["trace[1014511895] 'read index received' (duration: 257.525739ms)","trace[1014511895] 'applied index is now lower than readState.Index' (duration: 53.401µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:10:15.328643Z","caller":"traceutil/trace.go:171","msg":"trace[650423320] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"258.712877ms","start":"2026-02-21T17:10:15.069894Z","end":"2026-02-21T17:10:15.328607Z","steps":["trace[650423320] 'process raft request' (duration: 258.566083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:15.328789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.763535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-21T17:10:15.328849Z","caller":"traceutil/trace.go:171","msg":"trace[802267232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"257.851967ms","start":"2026-02-21T17:10:15.070983Z","end":"2026-02-21T17:10:15.328835Z","steps":["trace[802267232] 'agreement among raft nodes before linearized reading' (duration: 257.697373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:15.328884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.13874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-21T17:10:15.328794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.802463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:10:15.328927Z","caller":"traceutil/trace.go:171","msg":"trace[678473880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"257.196862ms","start":"2026-02-21T17:10:15.071717Z","end":"2026-02-21T17:10:15.328914Z","steps":["trace[678473880] 'agreement among raft nodes before linearized reading' (duration: 257.027228ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:15.328935Z","caller":"traceutil/trace.go:171","msg":"trace[293424208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"230.965587ms","start":"2026-02-21T17:10:15.097954Z","end":"2026-02-21T17:10:15.32892Z","steps":["trace[293424208] 'agreement among raft nodes before linearized reading' (duration: 230.815733ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:19.462482Z","caller":"traceutil/trace.go:171","msg":"trace[1489508831] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"115.631308ms","start":"2026-02-21T17:10:19.346825Z","end":"2026-02-21T17:10:19.462456Z","steps":["trace[1489508831] 'process raft request' (duration: 115.486095ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:19.798755Z","caller":"traceutil/trace.go:171","msg":"trace[453437435] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"433.134931ms","start":"2026-02-21T17:10:19.365601Z","end":"2026-02-21T17:10:19.798736Z","steps":["trace[453437435] 'read index received' (duration: 96.735515ms)","trace[453437435] 'applied index is now lower than readState.Index' (duration: 336.398756ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:10:19.799016Z","caller":"traceutil/trace.go:171","msg":"trace[227844552] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"452.074294ms","start":"2026-02-21T17:10:19.346934Z","end":"2026-02-21T17:10:19.799008Z","steps":["trace[227844552] 'process raft request' (duration: 451.689175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:19.799127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:19.346908Z","time spent":"452.139476ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:10:19.799285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.692393ms","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-21T17:10:19.799344Z","caller":"traceutil/trace.go:171","msg":"trace[157024620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"433.794586ms","start":"2026-02-21T17:10:19.36554Z","end":"2026-02-21T17:10:19.799335Z","steps":["trace[157024620] 'agreement among raft nodes before linearized reading' (duration: 433.717894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:19.799374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:19.365518Z","time spent":"433.848967ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:10:19.799572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.348509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tzng9\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-21T17:10:19.799605Z","caller":"traceutil/trace.go:171","msg":"trace[1305008725] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tzng9; range_end:; response_count:1; response_revision:532; }","duration":"176.409911ms","start":"2026-02-21T17:10:19.623187Z","end":"2026-02-21T17:10:19.799597Z","steps":["trace[1305008725] 'agreement among raft nodes before linearized reading' (duration: 176.300868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:20.569622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.463859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:10:20.569688Z","caller":"traceutil/trace.go:171","msg":"trace[157660053] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"767.082788ms","start":"2026-02-21T17:10:19.802594Z","end":"2026-02-21T17:10:20.569677Z","steps":["trace[157660053] 'process raft request' (duration: 438.534337ms)","trace[157660053] 'compare' (duration: 328.379647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:20.569719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:19.802574Z","time spent":"767.13131ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:20.661778Z","caller":"traceutil/trace.go:171","msg":"trace[1091710120] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"561.062395ms","start":"2026-02-21T17:10:20.100691Z","end":"2026-02-21T17:10:20.661753Z","steps":["trace[1091710120] 'read index received' (duration: 140.420247ms)","trace[1091710120] 'applied index is now lower than readState.Index' (duration: 420.641128ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:10:20.661829Z","caller":"traceutil/trace.go:171","msg":"trace[1580924779] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"850.823169ms","start":"2026-02-21T17:10:19.810985Z","end":"2026-02-21T17:10:20.661808Z","steps":["trace[1580924779] 'process raft request' (duration: 850.636004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:20.661935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.042607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-21T17:10:20.661966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:19.810973Z","time spent":"850.927241ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:20.661979Z","caller":"traceutil/trace.go:171","msg":"trace[195319954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"477.093428ms","start":"2026-02-21T17:10:20.184872Z","end":"2026-02-21T17:10:20.661965Z","steps":["trace[195319954] 'agreement among raft nodes before linearized reading' (duration: 477.011217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:20.662024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:20.184798Z","time spent":"477.204951ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-21T17:10:20.662073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.29171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:10:20.662235Z","caller":"traceutil/trace.go:171","msg":"trace[925101033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"561.525955ms","start":"2026-02-21T17:10:20.100651Z","end":"2026-02-21T17:10:20.662177Z","steps":["trace[925101033] 'agreement among raft nodes before linearized reading' (duration: 561.230069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:20.662302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:20.100633Z","time spent":"561.652978ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T17:10:20.835022Z","caller":"traceutil/trace.go:171","msg":"trace[1592830456] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"168.733141ms","start":"2026-02-21T17:10:20.666258Z","end":"2026-02-21T17:10:20.834991Z","steps":["trace[1592830456] 'process raft request' (duration: 117.607704ms)","trace[1592830456] 'compare' (duration: 50.978114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:21.822436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.679117ms","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-21T17:10:21.822486Z","caller":"traceutil/trace.go:171","msg":"trace[172635931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"355.770289ms","start":"2026-02-21T17:10:21.466702Z","end":"2026-02-21T17:10:21.822472Z","steps":["trace[172635931] 'range keys from in-memory index tree' (duration: 355.522273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:21.822715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:21.466682Z","time spent":"355.81844ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-21T17:10:22.088227Z","caller":"traceutil/trace.go:171","msg":"trace[1665291305] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"262.180759ms","start":"2026-02-21T17:10:21.826028Z","end":"2026-02-21T17:10:22.088209Z","steps":["trace[1665291305] 'process raft request' (duration: 262.065616ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:22.392215Z","caller":"traceutil/trace.go:171","msg":"trace[1750331907] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"292.10739ms","start":"2026-02-21T17:10:22.100079Z","end":"2026-02-21T17:10:22.392186Z","steps":["trace[1750331907] 'read index received' (duration: 199.832779ms)","trace[1750331907] 'applied index is now lower than readState.Index' (duration: 92.273801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:22.392378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.274513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:10:22.39241Z","caller":"traceutil/trace.go:171","msg":"trace[1844195486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"292.357096ms","start":"2026-02-21T17:10:22.100044Z","end":"2026-02-21T17:10:22.392401Z","steps":["trace[1844195486] 'agreement among raft nodes before linearized reading' (duration: 292.242713ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:22.392573Z","caller":"traceutil/trace.go:171","msg":"trace[1713291002] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"566.174965ms","start":"2026-02-21T17:10:21.826368Z","end":"2026-02-21T17:10:22.392543Z","steps":["trace[1713291002] 'process raft request' (duration: 473.537876ms)","trace[1713291002] 'compare' (duration: 92.144146ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:22.392707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:21.826346Z","time spent":"566.291457ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:22.690666Z","caller":"traceutil/trace.go:171","msg":"trace[523943940] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"112.708829ms","start":"2026-02-21T17:10:22.577897Z","end":"2026-02-21T17:10:22.690606Z","steps":["trace[523943940] 'process raft request' (duration: 82.680125ms)","trace[523943940] 'compare' (duration: 29.803398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:25.057515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:10:25.057605Z","caller":"traceutil/trace.go:171","msg":"trace[433250145] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:582; }","duration":"119.488408ms","start":"2026-02-21T17:10:24.938096Z","end":"2026-02-21T17:10:25.057585Z","steps":["trace[433250145] 'range keys from in-memory index tree' (duration: 119.220102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:25.057827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.849134ms","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-21T17:10:25.057981Z","caller":"traceutil/trace.go:171","msg":"trace[416519970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"137.042549ms","start":"2026-02-21T17:10:24.920914Z","end":"2026-02-21T17:10:25.057956Z","steps":["trace[416519970] 'range keys from in-memory index tree' (duration: 136.558828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:35.138544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.880035ms","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-21T17:10:35.138553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.421357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-vwnr9\" ","response":"range_response_count:1 size:4732"} {"level":"info","ts":"2026-02-21T17:10:35.138616Z","caller":"traceutil/trace.go:171","msg":"trace[1929553612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"394.996347ms","start":"2026-02-21T17:10:34.743604Z","end":"2026-02-21T17:10:35.138601Z","steps":["trace[1929553612] 'range keys from in-memory index tree' (duration: 394.764632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:35.138644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:34.743583Z","time spent":"395.053899ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-21T17:10:35.138647Z","caller":"traceutil/trace.go:171","msg":"trace[1186156087] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-vwnr9; range_end:; response_count:1; response_revision:677; }","duration":"427.57014ms","start":"2026-02-21T17:10:34.711056Z","end":"2026-02-21T17:10:35.138626Z","steps":["trace[1186156087] 'range keys from in-memory index tree' (duration: 427.279254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:35.138688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:34.711036Z","time spent":"427.642273ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4755,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-vwnr9\" "} {"level":"warn","ts":"2026-02-21T17:10:40.805247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.463369ms","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-21T17:10:40.80529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.275752ms","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-21T17:10:40.805352Z","caller":"traceutil/trace.go:171","msg":"trace[578563822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"333.393535ms","start":"2026-02-21T17:10:40.471944Z","end":"2026-02-21T17:10:40.805337Z","steps":["trace[578563822] 'range keys from in-memory index tree' (duration: 332.847812ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:40.805352Z","caller":"traceutil/trace.go:171","msg":"trace[1522840056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"193.609543ms","start":"2026-02-21T17:10:40.611719Z","end":"2026-02-21T17:10:40.805328Z","steps":["trace[1522840056] 'range keys from in-memory index tree' (duration: 193.136481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:40.805397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:40.471918Z","time spent":"333.459287ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-21T17:10:42.981004Z","caller":"traceutil/trace.go:171","msg":"trace[283070620] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"138.165774ms","start":"2026-02-21T17:10:42.842817Z","end":"2026-02-21T17:10:42.980982Z","steps":["trace[283070620] 'process raft request' (duration: 133.443393ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:43.323922Z","caller":"traceutil/trace.go:171","msg":"trace[739004628] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"114.07455ms","start":"2026-02-21T17:10:43.209827Z","end":"2026-02-21T17:10:43.323901Z","steps":["trace[739004628] 'read index received' (duration: 40.496898ms)","trace[739004628] 'applied index is now lower than readState.Index' (duration: 73.576942ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:10:43.32401Z","caller":"traceutil/trace.go:171","msg":"trace[659739980] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"235.809932ms","start":"2026-02-21T17:10:43.088156Z","end":"2026-02-21T17:10:43.323966Z","steps":["trace[659739980] 'process raft request' (duration: 162.169867ms)","trace[659739980] 'compare' (duration: 73.49036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:10:43.324097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.258405ms","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-21T17:10:43.324123Z","caller":"traceutil/trace.go:171","msg":"trace[96782619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"114.336727ms","start":"2026-02-21T17:10:43.209779Z","end":"2026-02-21T17:10:43.324115Z","steps":["trace[96782619] 'agreement among raft nodes before linearized reading' (duration: 114.226705ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:10:43.75413Z","caller":"traceutil/trace.go:171","msg":"trace[1955646951] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"426.868484ms","start":"2026-02-21T17:10:43.327246Z","end":"2026-02-21T17:10:43.754114Z","steps":["trace[1955646951] 'process raft request' (duration: 426.751531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:43.754233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:43.327231Z","time spent":"426.947385ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:43.772199Z","caller":"traceutil/trace.go:171","msg":"trace[1916208554] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:756; }","duration":"293.814869ms","start":"2026-02-21T17:10:43.478361Z","end":"2026-02-21T17:10:43.772176Z","steps":["trace[1916208554] 'read index received' (duration: 275.629333ms)","trace[1916208554] 'applied index is now lower than readState.Index' (duration: 18.184596ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:10:43.772282Z","caller":"traceutil/trace.go:171","msg":"trace[32634897] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"444.167879ms","start":"2026-02-21T17:10:43.328092Z","end":"2026-02-21T17:10:43.772259Z","steps":["trace[32634897] 'process raft request' (duration: 443.978474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:43.772324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.943222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:10:43.772364Z","caller":"traceutil/trace.go:171","msg":"trace[2027283414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"294.039654ms","start":"2026-02-21T17:10:43.478312Z","end":"2026-02-21T17:10:43.772352Z","steps":["trace[2027283414] 'agreement among raft nodes before linearized reading' (duration: 293.951922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:10:43.772397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:10:43.328074Z","time spent":"444.258152ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:10:44.425545Z","caller":"traceutil/trace.go:171","msg":"trace[1938062607] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"197.597316ms","start":"2026-02-21T17:10:44.22792Z","end":"2026-02-21T17:10:44.425517Z","steps":["trace[1938062607] 'process raft request' (duration: 197.456223ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:14.47032Z","caller":"traceutil/trace.go:171","msg":"trace[838833115] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"498.939011ms","start":"2026-02-21T17:11:13.971341Z","end":"2026-02-21T17:11:14.47028Z","steps":["trace[838833115] 'read index received' (duration: 498.564663ms)","trace[838833115] 'applied index is now lower than readState.Index' (duration: 372.508µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:14.47037Z","caller":"traceutil/trace.go:171","msg":"trace[2102141307] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"526.251503ms","start":"2026-02-21T17:11:13.944076Z","end":"2026-02-21T17:11:14.470328Z","steps":["trace[2102141307] 'process raft request' (duration: 525.970806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:14.470617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:13.944051Z","time spent":"526.434787ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:14.47067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.31075ms","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-21T17:11:14.470709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.976307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:14.470767Z","caller":"traceutil/trace.go:171","msg":"trace[262103633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"370.092309ms","start":"2026-02-21T17:11:14.100657Z","end":"2026-02-21T17:11:14.470749Z","steps":["trace[262103633] 'agreement among raft nodes before linearized reading' (duration: 369.974037ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:14.470768Z","caller":"traceutil/trace.go:171","msg":"trace[1780718032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"499.488394ms","start":"2026-02-21T17:11:13.971254Z","end":"2026-02-21T17:11:14.470742Z","steps":["trace[1780718032] 'agreement among raft nodes before linearized reading' (duration: 499.164797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:14.47082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:14.100641Z","time spent":"370.170072ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T17:11:14.470827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:13.971236Z","time spent":"499.580047ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:14.471305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.450457ms","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-21T17:11:14.471383Z","caller":"traceutil/trace.go:171","msg":"trace[913472454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"468.56922ms","start":"2026-02-21T17:11:14.0028Z","end":"2026-02-21T17:11:14.471369Z","steps":["trace[913472454] 'agreement among raft nodes before linearized reading' (duration: 467.767611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:14.471415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:14.002779Z","time spent":"468.628301ms","remote":"127.0.0.1:49642","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":"warn","ts":"2026-02-21T17:11:15.228827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.651222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:11:15.228933Z","caller":"traceutil/trace.go:171","msg":"trace[277068758] linearizableReadLoop","detail":"{readStateIndex:855; appliedIndex:854; }","duration":"576.074122ms","start":"2026-02-21T17:11:14.652844Z","end":"2026-02-21T17:11:15.228919Z","steps":["trace[277068758] 'read index received' (duration: 443.114472ms)","trace[277068758] 'applied index is now lower than readState.Index' (duration: 132.95814ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:15.229007Z","caller":"traceutil/trace.go:171","msg":"trace[957890266] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"752.892361ms","start":"2026-02-21T17:11:14.476104Z","end":"2026-02-21T17:11:15.228997Z","steps":["trace[957890266] 'process raft request' (duration: 619.992813ms)","trace[957890266] 'compare' (duration: 132.452946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:15.229061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:14.476095Z","time spent":"752.934631ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:15.229097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.279892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T17:11:15.229134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.282107ms","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-21T17:11:15.229179Z","caller":"traceutil/trace.go:171","msg":"trace[127010061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"123.405665ms","start":"2026-02-21T17:11:15.105749Z","end":"2026-02-21T17:11:15.229155Z","steps":["trace[127010061] 'agreement among raft nodes before linearized reading' (duration: 123.274192ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:15.22919Z","caller":"traceutil/trace.go:171","msg":"trace[2135787432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"576.37902ms","start":"2026-02-21T17:11:14.652793Z","end":"2026-02-21T17:11:15.229172Z","steps":["trace[2135787432] 'agreement among raft nodes before linearized reading' (duration: 576.238556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:15.22922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:14.652763Z","time spent":"576.452091ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-21T17:11:15.448658Z","caller":"traceutil/trace.go:171","msg":"trace[2141676132] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"215.443483ms","start":"2026-02-21T17:11:15.233188Z","end":"2026-02-21T17:11:15.448632Z","steps":["trace[2141676132] 'process raft request' (duration: 171.83696ms)","trace[2141676132] 'compare' (duration: 43.514631ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:15.619195Z","caller":"traceutil/trace.go:171","msg":"trace[2007441883] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:855; }","duration":"344.368038ms","start":"2026-02-21T17:11:15.274806Z","end":"2026-02-21T17:11:15.619174Z","steps":["trace[2007441883] 'read index received' (duration: 130.204324ms)","trace[2007441883] 'applied index is now lower than readState.Index' (duration: 214.162724ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:15.619336Z","caller":"traceutil/trace.go:171","msg":"trace[1597406668] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"385.885172ms","start":"2026-02-21T17:11:15.233415Z","end":"2026-02-21T17:11:15.6193Z","steps":["trace[1597406668] 'process raft request' (duration: 336.882872ms)","trace[1597406668] 'compare' (duration: 48.790345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:15.6194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.567683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-21T17:11:15.619443Z","caller":"traceutil/trace.go:171","msg":"trace[755529089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"344.665245ms","start":"2026-02-21T17:11:15.274765Z","end":"2026-02-21T17:11:15.61943Z","steps":["trace[755529089] 'agreement among raft nodes before linearized reading' (duration: 344.484441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:15.619441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:15.233396Z","time spent":"385.984844ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:15.619477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:15.274748Z","time spent":"344.719796ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-21T17:11:16.898182Z","caller":"traceutil/trace.go:171","msg":"trace[1192519741] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"132.809285ms","start":"2026-02-21T17:11:16.765345Z","end":"2026-02-21T17:11:16.898154Z","steps":["trace[1192519741] 'process raft request' (duration: 132.673902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:18.310934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.418314ms","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-21T17:11:18.311042Z","caller":"traceutil/trace.go:171","msg":"trace[1171633527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:826; }","duration":"632.583947ms","start":"2026-02-21T17:11:17.678434Z","end":"2026-02-21T17:11:18.311017Z","steps":["trace[1171633527] 'range keys from in-memory index tree' (duration: 632.195899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:18.311028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212189913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T17:11:18.3111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:17.678414Z","time spent":"632.67308ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-21T17:11:18.311123Z","caller":"traceutil/trace.go:171","msg":"trace[1220064970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:826; }","duration":"1.212318745s","start":"2026-02-21T17:11:17.098781Z","end":"2026-02-21T17:11:18.3111Z","steps":["trace[1220064970] 'range keys from in-memory index tree' (duration: 1.21208311s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:18.311186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:17.098746Z","time spent":"1.212428038s","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T17:11:18.311245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.485667ms","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-21T17:11:18.311297Z","caller":"traceutil/trace.go:171","msg":"trace[609336141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"858.535218ms","start":"2026-02-21T17:11:17.452749Z","end":"2026-02-21T17:11:18.311285Z","steps":["trace[609336141] 'range keys from in-memory index tree' (duration: 858.311912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:18.311366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:17.452693Z","time spent":"858.66479ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T17:11:18.311051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077880072s","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-21T17:11:18.311466Z","caller":"traceutil/trace.go:171","msg":"trace[1701729361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:826; }","duration":"1.078325262s","start":"2026-02-21T17:11:17.233115Z","end":"2026-02-21T17:11:18.31144Z","steps":["trace[1701729361] 'range keys from in-memory index tree' (duration: 1.077668188s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:18.311559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:17.233092Z","time spent":"1.078438715s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:18.311775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.008294ms","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-21T17:11:18.311819Z","caller":"traceutil/trace.go:171","msg":"trace[413870665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:826; }","duration":"687.083056ms","start":"2026-02-21T17:11:17.624727Z","end":"2026-02-21T17:11:18.31181Z","steps":["trace[413870665] 'range keys from in-memory index tree' (duration: 686.197775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:18.311847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:17.624706Z","time spent":"687.134426ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:18.445597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.285806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T17:11:18.445701Z","caller":"traceutil/trace.go:171","msg":"trace[1614272689] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"132.410905ms","start":"2026-02-21T17:11:18.313274Z","end":"2026-02-21T17:11:18.445685Z","steps":["trace[1614272689] 'read index received' (duration: 72.621µs)","trace[1614272689] 'applied index is now lower than readState.Index' (duration: 132.336784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:18.445798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.513078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:18.445833Z","caller":"traceutil/trace.go:171","msg":"trace[1923428353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:826; }","duration":"132.586689ms","start":"2026-02-21T17:11:18.313236Z","end":"2026-02-21T17:11:18.445823Z","steps":["trace[1923428353] 'agreement among raft nodes before linearized reading' (duration: 132.506787ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:18.450306Z","caller":"traceutil/trace.go:171","msg":"trace[1812634800] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"133.146032ms","start":"2026-02-21T17:11:18.317143Z","end":"2026-02-21T17:11:18.450289Z","steps":["trace[1812634800] 'process raft request' (duration: 133.028599ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:18.496966Z","caller":"traceutil/trace.go:171","msg":"trace[1003444771] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"179.593592ms","start":"2026-02-21T17:11:18.317348Z","end":"2026-02-21T17:11:18.496941Z","steps":["trace[1003444771] 'process raft request' (duration: 179.08908ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:18.497121Z","caller":"traceutil/trace.go:171","msg":"trace[64004671] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"179.780256ms","start":"2026-02-21T17:11:18.317331Z","end":"2026-02-21T17:11:18.497112Z","steps":["trace[64004671] 'process raft request' (duration: 178.964288ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:18.497209Z","caller":"traceutil/trace.go:171","msg":"trace[731173110] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"178.922886ms","start":"2026-02-21T17:11:18.318265Z","end":"2026-02-21T17:11:18.497188Z","steps":["trace[731173110] 'process raft request' (duration: 178.20662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:21.240541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.447146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:11:21.240856Z","caller":"traceutil/trace.go:171","msg":"trace[1384393795] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"730.911717ms","start":"2026-02-21T17:11:20.509923Z","end":"2026-02-21T17:11:21.240835Z","steps":["trace[1384393795] 'process raft request' (duration: 127.179373ms)","trace[1384393795] 'compare' (duration: 603.237271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:21.240938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:20.509902Z","time spent":"730.996328ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:21.639787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002731451s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-21T17:11:21.958395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.317475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:11:21.958581Z","caller":"traceutil/trace.go:171","msg":"trace[53815791] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:867; }","duration":"1.34592682s","start":"2026-02-21T17:11:20.612638Z","end":"2026-02-21T17:11:21.958565Z","steps":["trace[53815791] 'read index received' (duration: 24.430044ms)","trace[53815791] 'applied index is now lower than readState.Index' (duration: 1.321495816s)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:21.958676Z","caller":"traceutil/trace.go:171","msg":"trace[1154454330] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"1.445296491s","start":"2026-02-21T17:11:20.513369Z","end":"2026-02-21T17:11:21.958666Z","steps":["trace[1154454330] 'process raft request' (duration: 1.445131537s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:21.958703Z","caller":"traceutil/trace.go:171","msg":"trace[1350119679] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"1.447334919s","start":"2026-02-21T17:11:20.511345Z","end":"2026-02-21T17:11:21.95868Z","steps":["trace[1350119679] 'process raft request' (duration: 1.128651915s)","trace[1350119679] 'compare' (duration: 318.143601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:21.959564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:20.513354Z","time spent":"1.446103479s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:21.959746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:20.511289Z","time spent":"1.448304121s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:21.958786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.392936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:21.959995Z","caller":"traceutil/trace.go:171","msg":"trace[1086104981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"857.629615ms","start":"2026-02-21T17:11:21.102347Z","end":"2026-02-21T17:11:21.959977Z","steps":["trace[1086104981] 'agreement among raft nodes before linearized reading' (duration: 856.403027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:21.960074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:21.102331Z","time spent":"857.723147ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T17:11:21.958878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346251407s","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-21T17:11:21.960191Z","caller":"traceutil/trace.go:171","msg":"trace[1780774741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"1.347599028s","start":"2026-02-21T17:11:20.612573Z","end":"2026-02-21T17:11:21.960172Z","steps":["trace[1780774741] 'agreement among raft nodes before linearized reading' (duration: 1.346229647s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:21.960246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:20.612541Z","time spent":"1.347691371s","remote":"127.0.0.1:49642","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":"warn","ts":"2026-02-21T17:11:23.318904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.700446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:11:23.319089Z","caller":"traceutil/trace.go:171","msg":"trace[794489135] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"934.597261ms","start":"2026-02-21T17:11:22.384476Z","end":"2026-02-21T17:11:23.319073Z","steps":["trace[794489135] 'read index received' (duration: 26.941µs)","trace[794489135] 'applied index is now lower than readState.Index' (duration: 934.56883ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:23.319186Z","caller":"traceutil/trace.go:171","msg":"trace[493555151] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"1.354644104s","start":"2026-02-21T17:11:21.96453Z","end":"2026-02-21T17:11:23.319174Z","steps":["trace[493555151] 'process raft request' (duration: 378.611299ms)","trace[493555151] 'compare' (duration: 975.467411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:23.319277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:21.964515Z","time spent":"1.354728516s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:23.319519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.041912ms","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-21T17:11:23.319589Z","caller":"traceutil/trace.go:171","msg":"trace[1602536340] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:836; }","duration":"935.143115ms","start":"2026-02-21T17:11:22.384435Z","end":"2026-02-21T17:11:23.319578Z","steps":["trace[1602536340] 'agreement among raft nodes before linearized reading' (duration: 935.032473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.319637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:22.384419Z","time spent":"935.207556ms","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-21T17:11:23.319697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.473358ms","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-21T17:11:23.319737Z","caller":"traceutil/trace.go:171","msg":"trace[1051823089] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:836; }","duration":"673.571731ms","start":"2026-02-21T17:11:22.646156Z","end":"2026-02-21T17:11:23.319728Z","steps":["trace[1051823089] 'agreement among raft nodes before linearized reading' (duration: 673.506759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.319768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:22.646104Z","time spent":"673.655332ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":10,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-21T17:11:23.319906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.970565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:23.319939Z","caller":"traceutil/trace.go:171","msg":"trace[1931484580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"219.055528ms","start":"2026-02-21T17:11:23.100875Z","end":"2026-02-21T17:11:23.319931Z","steps":["trace[1931484580] 'agreement among raft nodes before linearized reading' (duration: 219.008327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.320256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.987903ms","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-21T17:11:23.320292Z","caller":"traceutil/trace.go:171","msg":"trace[1712542616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"811.055734ms","start":"2026-02-21T17:11:22.509227Z","end":"2026-02-21T17:11:23.320283Z","steps":["trace[1712542616] 'agreement among raft nodes before linearized reading' (duration: 810.970412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.320317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:22.509208Z","time spent":"811.102226ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:23.320468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.030704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-21T17:11:23.320532Z","caller":"traceutil/trace.go:171","msg":"trace[609767788] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:836; }","duration":"814.637268ms","start":"2026-02-21T17:11:22.50588Z","end":"2026-02-21T17:11:23.320517Z","steps":["trace[609767788] 'agreement among raft nodes before linearized reading' (duration: 813.640305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.320913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:22.505865Z","time spent":"814.820023ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.18\" "} {"level":"warn","ts":"2026-02-21T17:11:23.627448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.72551ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-21T17:11:23.627622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:23.322373Z","time spent":"305.237759ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-21T17:11:23.631761Z","caller":"traceutil/trace.go:171","msg":"trace[70594261] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"306.880167ms","start":"2026-02-21T17:11:23.324844Z","end":"2026-02-21T17:11:23.631724Z","steps":["trace[70594261] 'process raft request' (duration: 302.815452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.631944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:23.324825Z","time spent":"306.969619ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:11:23.632182Z","caller":"traceutil/trace.go:171","msg":"trace[1994979154] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"307.232435ms","start":"2026-02-21T17:11:23.324936Z","end":"2026-02-21T17:11:23.632168Z","steps":["trace[1994979154] 'process raft request' (duration: 302.887834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:23.632411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:23.32493Z","time spent":"307.288996ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:11:29.78827Z","caller":"traceutil/trace.go:171","msg":"trace[410984331] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"131.469234ms","start":"2026-02-21T17:11:29.656781Z","end":"2026-02-21T17:11:29.78825Z","steps":["trace[410984331] 'process raft request' (duration: 116.576973ms)","trace[410984331] 'compare' (duration: 14.806209ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:30.31456Z","caller":"traceutil/trace.go:171","msg":"trace[1960003965] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"214.483832ms","start":"2026-02-21T17:11:30.099981Z","end":"2026-02-21T17:11:30.314465Z","steps":["trace[1960003965] 'read index received' (duration: 212.520395ms)","trace[1960003965] 'applied index is now lower than readState.Index' (duration: 1.961457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:30.314855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.85611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:30.314906Z","caller":"traceutil/trace.go:171","msg":"trace[1701906099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"214.989433ms","start":"2026-02-21T17:11:30.099898Z","end":"2026-02-21T17:11:30.314888Z","steps":["trace[1701906099] 'agreement among raft nodes before linearized reading' (duration: 214.747968ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:30.315436Z","caller":"traceutil/trace.go:171","msg":"trace[1086406155] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"274.9953ms","start":"2026-02-21T17:11:30.040411Z","end":"2026-02-21T17:11:30.315406Z","steps":["trace[1086406155] 'process raft request' (duration: 272.121053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:36.255621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.618621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-21T17:11:36.255693Z","caller":"traceutil/trace.go:171","msg":"trace[1395272242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"193.755885ms","start":"2026-02-21T17:11:36.061922Z","end":"2026-02-21T17:11:36.255678Z","steps":["trace[1395272242] 'range keys from in-memory index tree' (duration: 193.445277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:38.312354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993700545800999704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-21T17:11:38.480187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06113416s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T17:11:38.480631Z","caller":"traceutil/trace.go:171","msg":"trace[174564049] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.061652533s","start":"2026-02-21T17:11:37.418948Z","end":"2026-02-21T17:11:38.4806Z","steps":["trace[174564049] 'process raft request' (duration: 1.061496069s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:38.480784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:37.418929Z","time spent":"1.061776595s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:39.799184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.792732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:11:39.799292Z","caller":"traceutil/trace.go:171","msg":"trace[1221026380] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"2.330721579s","start":"2026-02-21T17:11:37.468545Z","end":"2026-02-21T17:11:39.799267Z","steps":["trace[1221026380] 'process raft request' (duration: 2.024720832s)","trace[1221026380] 'compare' (duration: 305.368563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:39.799402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:37.468518Z","time spent":"2.330842631s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:11:39.799462Z","caller":"traceutil/trace.go:171","msg":"trace[138859698] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"1.987815346s","start":"2026-02-21T17:11:37.811557Z","end":"2026-02-21T17:11:39.799372Z","steps":["trace[138859698] 'read index received' (duration: 668.86153ms)","trace[138859698] 'applied index is now lower than readState.Index' (duration: 1.318948745s)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:39.815514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973264694s","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-21T17:11:39.815547Z","caller":"traceutil/trace.go:171","msg":"trace[2062784351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.973320026s","start":"2026-02-21T17:11:37.842218Z","end":"2026-02-21T17:11:39.815538Z","steps":["trace[2062784351] 'agreement among raft nodes before linearized reading' (duration: 1.973230404s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:39.815535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003966835s","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-21T17:11:39.815565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:37.842204Z","time spent":"1.973357737s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-21T17:11:39.815587Z","caller":"traceutil/trace.go:171","msg":"trace[2134004005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"2.004049327s","start":"2026-02-21T17:11:37.81152Z","end":"2026-02-21T17:11:39.815569Z","steps":["trace[2134004005] 'agreement among raft nodes before linearized reading' (duration: 1.988094092s)","trace[2134004005] 'range keys from in-memory index tree' (duration: 15.816561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:39.815627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:37.811502Z","time spent":"2.004116698s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T17:11:39.815671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552073275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-21T17:11:39.815688Z","caller":"traceutil/trace.go:171","msg":"trace[1731179099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.552113435s","start":"2026-02-21T17:11:38.26357Z","end":"2026-02-21T17:11:39.815684Z","steps":["trace[1731179099] 'agreement among raft nodes before linearized reading' (duration: 1.552067534s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:39.815702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:38.263557Z","time spent":"1.552141777s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:39.815712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439940645s","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-21T17:11:39.815776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714859333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:39.815792Z","caller":"traceutil/trace.go:171","msg":"trace[1206274514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.714873354s","start":"2026-02-21T17:11:38.100913Z","end":"2026-02-21T17:11:39.815787Z","steps":["trace[1206274514] 'agreement among raft nodes before linearized reading' (duration: 1.714852363s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:39.815804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:38.100841Z","time spent":"1.714960775s","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T17:11:39.815816Z","caller":"traceutil/trace.go:171","msg":"trace[1232328144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.440057498s","start":"2026-02-21T17:11:38.375724Z","end":"2026-02-21T17:11:39.815781Z","steps":["trace[1232328144] 'agreement among raft nodes before linearized reading' (duration: 1.43974309s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:39.815864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:38.375689Z","time spent":"1.440167479s","remote":"127.0.0.1:49642","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-21T17:11:39.815757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658399899s","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":"info","ts":"2026-02-21T17:11:39.816003Z","caller":"traceutil/trace.go:171","msg":"trace[1145076852] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:929; }","duration":"1.658646495s","start":"2026-02-21T17:11:38.157352Z","end":"2026-02-21T17:11:39.815998Z","steps":["trace[1145076852] 'agreement among raft nodes before linearized reading' (duration: 1.658385339s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:39.816021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:38.157307Z","time spent":"1.658709026s","remote":"127.0.0.1:49744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-21T17:11:52.729979Z","caller":"traceutil/trace.go:171","msg":"trace[1009622400] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"199.677164ms","start":"2026-02-21T17:11:52.530222Z","end":"2026-02-21T17:11:52.729899Z","steps":["trace[1009622400] 'read index received' (duration: 198.733232ms)","trace[1009622400] 'applied index is now lower than readState.Index' (duration: 941.672µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:52.73021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.9677ms","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-21T17:11:52.730256Z","caller":"traceutil/trace.go:171","msg":"trace[549644826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"200.047663ms","start":"2026-02-21T17:11:52.530185Z","end":"2026-02-21T17:11:52.730233Z","steps":["trace[549644826] 'agreement among raft nodes before linearized reading' (duration: 199.874359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:58.474324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.380737ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T17:11:58.474422Z","caller":"traceutil/trace.go:171","msg":"trace[1742460965] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"499.522018ms","start":"2026-02-21T17:11:57.974883Z","end":"2026-02-21T17:11:58.474405Z","steps":["trace[1742460965] 'read index received' (duration: 54.003948ms)","trace[1742460965] 'applied index is now lower than readState.Index' (duration: 445.5168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:58.474602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.717692ms","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-21T17:11:58.474612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.341324ms","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-21T17:11:58.474636Z","caller":"traceutil/trace.go:171","msg":"trace[1467776290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"499.808624ms","start":"2026-02-21T17:11:57.974818Z","end":"2026-02-21T17:11:58.474626Z","steps":["trace[1467776290] 'agreement among raft nodes before linearized reading' (duration: 499.669281ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:58.474659Z","caller":"traceutil/trace.go:171","msg":"trace[532241911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"404.397606ms","start":"2026-02-21T17:11:58.070246Z","end":"2026-02-21T17:11:58.474644Z","steps":["trace[532241911] 'agreement among raft nodes before linearized reading' (duration: 404.272903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:58.474679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:57.974798Z","time spent":"499.863436ms","remote":"127.0.0.1:49642","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-21T17:11:58.474708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.070243Z","time spent":"404.440967ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:58.474721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.901428ms","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-21T17:11:58.474791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.03973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:11:58.474819Z","caller":"traceutil/trace.go:171","msg":"trace[408807467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"373.099401ms","start":"2026-02-21T17:11:58.101709Z","end":"2026-02-21T17:11:58.474809Z","steps":["trace[408807467] 'agreement among raft nodes before linearized reading' (duration: 373.062061ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:58.474815Z","caller":"traceutil/trace.go:171","msg":"trace[2006147385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"405.020981ms","start":"2026-02-21T17:11:58.069755Z","end":"2026-02-21T17:11:58.474776Z","steps":["trace[2006147385] 'agreement among raft nodes before linearized reading' (duration: 404.767515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:58.474844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.101681Z","time spent":"373.157903ms","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T17:11:58.474866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.069738Z","time spent":"405.115412ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T17:11:58.474873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.62215ms","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-21T17:11:58.47495Z","caller":"traceutil/trace.go:171","msg":"trace[498864741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"404.714703ms","start":"2026-02-21T17:11:58.070215Z","end":"2026-02-21T17:11:58.474929Z","steps":["trace[498864741] 'agreement among raft nodes before linearized reading' (duration: 404.492368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:58.474993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.070208Z","time spent":"404.777865ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:11:59.1619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993700545800999881,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T17:11:59.252613Z","caller":"traceutil/trace.go:171","msg":"trace[2416646] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"768.859496ms","start":"2026-02-21T17:11:58.483708Z","end":"2026-02-21T17:11:59.252568Z","steps":["trace[2416646] 'process raft request' (duration: 768.64946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:59.252913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.483694Z","time spent":"769.027959ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:11:59.489356Z","caller":"traceutil/trace.go:171","msg":"trace[1970845016] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1044; }","duration":"827.515081ms","start":"2026-02-21T17:11:58.66182Z","end":"2026-02-21T17:11:59.489335Z","steps":["trace[1970845016] 'read index received' (duration: 590.579993ms)","trace[1970845016] 'applied index is now lower than readState.Index' (duration: 236.934188ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:11:59.489391Z","caller":"traceutil/trace.go:171","msg":"trace[2050691301] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.005545647s","start":"2026-02-21T17:11:58.483809Z","end":"2026-02-21T17:11:59.489355Z","steps":["trace[2050691301] 'process raft request' (duration: 1.005403104s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:59.489487Z","caller":"traceutil/trace.go:171","msg":"trace[415709119] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.005623969s","start":"2026-02-21T17:11:58.48384Z","end":"2026-02-21T17:11:59.489464Z","steps":["trace[415709119] 'process raft request' (duration: 1.005458905s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:59.48955Z","caller":"traceutil/trace.go:171","msg":"trace[2010458449] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.005809863s","start":"2026-02-21T17:11:58.483721Z","end":"2026-02-21T17:11:59.489531Z","steps":["trace[2010458449] 'process raft request' (duration: 988.451756ms)","trace[2010458449] 'compare' (duration: 16.929957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:59.489572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.483818Z","time spent":"1.00569467s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:59.489638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.483705Z","time spent":"1.005888305s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:59.489722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.424845ms","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-21T17:11:59.48973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.882909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T17:11:59.48978Z","caller":"traceutil/trace.go:171","msg":"trace[1724335680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"589.532688ms","start":"2026-02-21T17:11:58.900234Z","end":"2026-02-21T17:11:59.489767Z","steps":["trace[1724335680] 'agreement among raft nodes before linearized reading' (duration: 589.350064ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:11:59.489791Z","caller":"traceutil/trace.go:171","msg":"trace[620176376] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1003; }","duration":"827.993912ms","start":"2026-02-21T17:11:58.661778Z","end":"2026-02-21T17:11:59.489772Z","steps":["trace[620176376] 'agreement among raft nodes before linearized reading' (duration: 827.862379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:11:59.489819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.900215Z","time spent":"589.59627ms","remote":"127.0.0.1:49642","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":"warn","ts":"2026-02-21T17:11:59.489835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.661756Z","time spent":"828.065173ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-21T17:11:59.490182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:58.483801Z","time spent":"1.006319295s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:11:59.814599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.353898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:11:59.814718Z","caller":"traceutil/trace.go:171","msg":"trace[232270911] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"320.14281ms","start":"2026-02-21T17:11:59.494549Z","end":"2026-02-21T17:11:59.814692Z","steps":["trace[232270911] 'process raft request' (duration: 128.612997ms)","trace[232270911] 'compare' (duration: 191.217085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:11:59.814778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:11:59.494527Z","time spent":"320.221991ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:00.244661Z","caller":"traceutil/trace.go:171","msg":"trace[580709236] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"143.833334ms","start":"2026-02-21T17:12:00.100805Z","end":"2026-02-21T17:12:00.244638Z","steps":["trace[580709236] 'read index received' (duration: 143.740612ms)","trace[580709236] 'applied index is now lower than readState.Index' (duration: 91.802µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:12:00.244857Z","caller":"traceutil/trace.go:171","msg":"trace[1630262006] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"175.954138ms","start":"2026-02-21T17:12:00.068882Z","end":"2026-02-21T17:12:00.244836Z","steps":["trace[1630262006] 'process raft request' (duration: 175.596059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:00.244998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.186943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:00.245032Z","caller":"traceutil/trace.go:171","msg":"trace[1042649833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"144.264864ms","start":"2026-02-21T17:12:00.100757Z","end":"2026-02-21T17:12:00.245022Z","steps":["trace[1042649833] 'agreement among raft nodes before linearized reading' (duration: 144.203043ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:00.400327Z","caller":"traceutil/trace.go:171","msg":"trace[1850151101] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"155.580799ms","start":"2026-02-21T17:12:00.244721Z","end":"2026-02-21T17:12:00.400302Z","steps":["trace[1850151101] 'read index received' (duration: 155.440516ms)","trace[1850151101] 'applied index is now lower than readState.Index' (duration: 137.373µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:12:00.400474Z","caller":"traceutil/trace.go:171","msg":"trace[80496807] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"205.486059ms","start":"2026-02-21T17:12:00.194954Z","end":"2026-02-21T17:12:00.40044Z","steps":["trace[80496807] 'process raft request' (duration: 205.232934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:00.400546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.244743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-21T17:12:00.400544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.35348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:00.400593Z","caller":"traceutil/trace.go:171","msg":"trace[1788679042] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1006; }","duration":"251.341266ms","start":"2026-02-21T17:12:00.149238Z","end":"2026-02-21T17:12:00.40058Z","steps":["trace[1788679042] 'agreement among raft nodes before linearized reading' (duration: 251.193222ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:00.400614Z","caller":"traceutil/trace.go:171","msg":"trace[456503030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"154.465963ms","start":"2026-02-21T17:12:00.246134Z","end":"2026-02-21T17:12:00.4006Z","steps":["trace[456503030] 'agreement among raft nodes before linearized reading' (duration: 154.34169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:01.958737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.810693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-21T17:12:01.958767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.568476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-21T17:12:01.958831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.812223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:01.958884Z","caller":"traceutil/trace.go:171","msg":"trace[299046251] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1007; }","duration":"672.894815ms","start":"2026-02-21T17:12:01.285974Z","end":"2026-02-21T17:12:01.958869Z","steps":["trace[299046251] 'range keys from in-memory index tree' (duration: 672.751362ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:01.958885Z","caller":"traceutil/trace.go:171","msg":"trace[859977935] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1007; }","duration":"605.769301ms","start":"2026-02-21T17:12:01.353093Z","end":"2026-02-21T17:12:01.958862Z","steps":["trace[859977935] 'count revisions from in-memory index tree' (duration: 605.402832ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:01.958922Z","caller":"traceutil/trace.go:171","msg":"trace[571448683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"465.008648ms","start":"2026-02-21T17:12:01.493839Z","end":"2026-02-21T17:12:01.958848Z","steps":["trace[571448683] 'range keys from in-memory index tree' (duration: 464.609649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:01.958933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.285958Z","time spent":"672.965786ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-21T17:12:01.958947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.35306Z","time spent":"605.868102ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-21T17:12:01.958965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.493827Z","time spent":"465.127191ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:12:01.95895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.757558ms","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-21T17:12:01.958972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.181812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-21T17:12:01.959034Z","caller":"traceutil/trace.go:171","msg":"trace[1827273358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"139.901061ms","start":"2026-02-21T17:12:01.819114Z","end":"2026-02-21T17:12:01.959015Z","steps":["trace[1827273358] 'range keys from in-memory index tree' (duration: 139.581734ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:01.959072Z","caller":"traceutil/trace.go:171","msg":"trace[1734715292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"702.316924ms","start":"2026-02-21T17:12:01.256732Z","end":"2026-02-21T17:12:01.959049Z","steps":["trace[1734715292] 'range keys from in-memory index tree' (duration: 702.021098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:01.95913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.256715Z","time spent":"702.402497ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T17:12:01.959226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.848931ms","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-21T17:12:01.959248Z","caller":"traceutil/trace.go:171","msg":"trace[494563626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"463.951944ms","start":"2026-02-21T17:12:01.49529Z","end":"2026-02-21T17:12:01.959242Z","steps":["trace[494563626] 'range keys from in-memory index tree' (duration: 463.435901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:01.959264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.495269Z","time spent":"463.989364ms","remote":"127.0.0.1:49642","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-21T17:12:01.959444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.21673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-21T17:12:01.95952Z","caller":"traceutil/trace.go:171","msg":"trace[2033524494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"465.669873ms","start":"2026-02-21T17:12:01.493838Z","end":"2026-02-21T17:12:01.959508Z","steps":["trace[2033524494] 'range keys from in-memory index tree' (duration: 463.596565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:01.959553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.493809Z","time spent":"465.734525ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T17:12:01.959792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.380098Z","time spent":"579.690918ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-21T17:12:02.514577Z","caller":"traceutil/trace.go:171","msg":"trace[673302498] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"551.585188ms","start":"2026-02-21T17:12:01.962957Z","end":"2026-02-21T17:12:02.514542Z","steps":["trace[673302498] 'process raft request' (duration: 551.394873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:02.514813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.962936Z","time spent":"551.760442ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:03.558691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.045800161s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T17:12:03.559047Z","caller":"traceutil/trace.go:171","msg":"trace[180147427] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.595607178s","start":"2026-02-21T17:12:01.963421Z","end":"2026-02-21T17:12:03.559028Z","steps":["trace[180147427] 'process raft request' (duration: 1.595552867s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.559138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.963412Z","time spent":"1.595667689s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:03.559354Z","caller":"traceutil/trace.go:171","msg":"trace[1344890770] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.59610549s","start":"2026-02-21T17:12:01.963242Z","end":"2026-02-21T17:12:03.559347Z","steps":["trace[1344890770] 'process raft request' (duration: 1.595621308s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.559387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.963224Z","time spent":"1.59614682s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:03.559438Z","caller":"traceutil/trace.go:171","msg":"trace[791398578] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.595916195s","start":"2026-02-21T17:12:01.963501Z","end":"2026-02-21T17:12:03.559417Z","steps":["trace[791398578] 'process raft request' (duration: 1.595500346s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.559603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.963486Z","time spent":"1.596036098s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:03.559671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459563727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:03.559698Z","caller":"traceutil/trace.go:171","msg":"trace[1707261008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"1.459624048s","start":"2026-02-21T17:12:02.100067Z","end":"2026-02-21T17:12:03.559691Z","steps":["trace[1707261008] 'agreement among raft nodes before linearized reading' (duration: 1.459567786s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.55973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:02.100049Z","time spent":"1.459675289s","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T17:12:03.559823Z","caller":"traceutil/trace.go:171","msg":"trace[42920245] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.596289933s","start":"2026-02-21T17:12:01.963526Z","end":"2026-02-21T17:12:03.559816Z","steps":["trace[42920245] 'process raft request' (duration: 1.595761401s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.559851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.963519Z","time spent":"1.596318934s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-21T17:12:03.55988Z","caller":"traceutil/trace.go:171","msg":"trace[598778806] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.59532545s","start":"2026-02-21T17:12:01.964518Z","end":"2026-02-21T17:12:03.559843Z","steps":["trace[598778806] 'process raft request' (duration: 1.595059204s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:03.559623Z","caller":"traceutil/trace.go:171","msg":"trace[236058852] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1052; }","duration":"1.459511726s","start":"2026-02-21T17:12:02.100105Z","end":"2026-02-21T17:12:03.559617Z","steps":["trace[236058852] 'read index received' (duration: 414.146225ms)","trace[236058852] 'applied index is now lower than readState.Index' (duration: 1.045364951s)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:12:03.560099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:01.964512Z","time spent":"1.595460144s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:03.709979Z","caller":"traceutil/trace.go:171","msg":"trace[1401753086] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.191754916s","start":"2026-02-21T17:12:02.51819Z","end":"2026-02-21T17:12:03.709945Z","steps":["trace[1401753086] 'process raft request' (duration: 1.122585553s)","trace[1401753086] 'compare' (duration: 69.04677ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:12:03.710039Z","caller":"traceutil/trace.go:171","msg":"trace[1181575291] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"149.934777ms","start":"2026-02-21T17:12:03.560091Z","end":"2026-02-21T17:12:03.710026Z","steps":["trace[1181575291] 'read index received' (duration: 80.593501ms)","trace[1181575291] 'applied index is now lower than readState.Index' (duration: 69.339566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:12:03.710089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.298162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T17:12:03.710145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:02.518156Z","time spent":"1.191894679s","remote":"127.0.0.1:49462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-21T17:12:03.710156Z","caller":"traceutil/trace.go:171","msg":"trace[271513715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"230.408205ms","start":"2026-02-21T17:12:03.479736Z","end":"2026-02-21T17:12:03.710144Z","steps":["trace[271513715] 'agreement among raft nodes before linearized reading' (duration: 230.297723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.710246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202429455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-21T17:12:03.710285Z","caller":"traceutil/trace.go:171","msg":"trace[1123420648] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:1015; }","duration":"1.202519137s","start":"2026-02-21T17:12:02.507757Z","end":"2026-02-21T17:12:03.710276Z","steps":["trace[1123420648] 'agreement among raft nodes before linearized reading' (duration: 1.202340183s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.710328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:02.507741Z","time spent":"1.202565438s","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.18\" "} {"level":"info","ts":"2026-02-21T17:12:03.70999Z","caller":"traceutil/trace.go:171","msg":"trace[888898751] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"135.803626ms","start":"2026-02-21T17:12:03.574168Z","end":"2026-02-21T17:12:03.709972Z","steps":["trace[888898751] 'process raft request' (duration: 135.770885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.710481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.128882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:03.710509Z","caller":"traceutil/trace.go:171","msg":"trace[1417860898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"150.177253ms","start":"2026-02-21T17:12:03.560325Z","end":"2026-02-21T17:12:03.710503Z","steps":["trace[1417860898] 'agreement among raft nodes before linearized reading' (duration: 150.134012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:03.710616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.035255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-21T17:12:03.710646Z","caller":"traceutil/trace.go:171","msg":"trace[202895660] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1015; }","duration":"137.113747ms","start":"2026-02-21T17:12:03.573525Z","end":"2026-02-21T17:12:03.710639Z","steps":["trace[202895660] 'agreement among raft nodes before linearized reading' (duration: 137.017494ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:03.919153Z","caller":"traceutil/trace.go:171","msg":"trace[1237263153] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"203.968253ms","start":"2026-02-21T17:12:03.715157Z","end":"2026-02-21T17:12:03.919125Z","steps":["trace[1237263153] 'process raft request' (duration: 203.901932ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:03.9194Z","caller":"traceutil/trace.go:171","msg":"trace[1432439039] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"205.806727ms","start":"2026-02-21T17:12:03.713568Z","end":"2026-02-21T17:12:03.919375Z","steps":["trace[1432439039] 'process raft request' (duration: 205.327186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:04.211055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.566792ms","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-21T17:12:04.211155Z","caller":"traceutil/trace.go:171","msg":"trace[1235545272] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1017; }","duration":"237.677045ms","start":"2026-02-21T17:12:03.973465Z","end":"2026-02-21T17:12:04.211142Z","steps":["trace[1235545272] 'range keys from in-memory index tree' (duration: 237.554752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:04.211195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.407558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:12:04.211258Z","caller":"traceutil/trace.go:171","msg":"trace[203486389] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"353.045851ms","start":"2026-02-21T17:12:03.858201Z","end":"2026-02-21T17:12:04.211247Z","steps":["trace[203486389] 'process raft request' (duration: 202.431038ms)","trace[203486389] 'compare' (duration: 150.263305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:12:04.211306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:03.858134Z","time spent":"353.148184ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:05.919255Z","caller":"traceutil/trace.go:171","msg":"trace[1500727062] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"353.787018ms","start":"2026-02-21T17:12:05.565445Z","end":"2026-02-21T17:12:05.919232Z","steps":["trace[1500727062] 'process raft request' (duration: 353.598744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:05.919498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:05.56543Z","time spent":"353.921101ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:07.746003Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.808499492s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T17:12:07.746581Z","caller":"traceutil/trace.go:171","msg":"trace[245579175] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"2.179628437s","start":"2026-02-21T17:12:05.566926Z","end":"2026-02-21T17:12:07.746554Z","steps":["trace[245579175] 'process raft request' (duration: 2.179531615s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:07.746718Z","caller":"traceutil/trace.go:171","msg":"trace[1849328079] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"2.180281244s","start":"2026-02-21T17:12:05.566365Z","end":"2026-02-21T17:12:07.746646Z","steps":["trace[1849328079] 'process raft request' (duration: 2.179908745s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:07.746872Z","caller":"traceutil/trace.go:171","msg":"trace[1957236905] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1065; }","duration":"2.150039423s","start":"2026-02-21T17:12:05.59681Z","end":"2026-02-21T17:12:07.74685Z","steps":["trace[1957236905] 'read index received' (duration: 322.269039ms)","trace[1957236905] 'applied index is now lower than readState.Index' (duration: 1.827769524s)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:12:07.746891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:05.566353Z","time spent":"2.180442087s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:07.74704Z","caller":"traceutil/trace.go:171","msg":"trace[1557675389] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"2.135240276s","start":"2026-02-21T17:12:05.611784Z","end":"2026-02-21T17:12:07.747024Z","steps":["trace[1557675389] 'process raft request' (duration: 2.13495722s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:07.747036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:05.566918Z","time spent":"2.179814532s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:07.747222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.150416843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:07.747074Z","caller":"traceutil/trace.go:171","msg":"trace[759706388] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"2.179547045s","start":"2026-02-21T17:12:05.567499Z","end":"2026-02-21T17:12:07.747046Z","steps":["trace[759706388] 'process raft request' (duration: 2.179009162s)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:07.747262Z","caller":"traceutil/trace.go:171","msg":"trace[1690006971] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1024; }","duration":"2.150495325s","start":"2026-02-21T17:12:05.596757Z","end":"2026-02-21T17:12:07.747252Z","steps":["trace[1690006971] 'agreement among raft nodes before linearized reading' (duration: 2.150431133s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:07.747273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645439186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:07.747364Z","caller":"traceutil/trace.go:171","msg":"trace[1949091543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"1.645595511s","start":"2026-02-21T17:12:06.101756Z","end":"2026-02-21T17:12:07.747352Z","steps":["trace[1949091543] 'agreement among raft nodes before linearized reading' (duration: 1.645445137s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:07.747299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:05.596734Z","time spent":"2.150555875s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-21T17:12:07.747435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:06.101737Z","time spent":"1.645661023s","remote":"127.0.0.1:49376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T17:12:07.747407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:05.56749Z","time spent":"2.17979735s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:07.74713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:05.611759Z","time spent":"2.135323848s","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:07.747469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177671744s","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-21T17:12:07.747624Z","caller":"traceutil/trace.go:171","msg":"trace[1759682075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"1.177897669s","start":"2026-02-21T17:12:06.569712Z","end":"2026-02-21T17:12:07.74761Z","steps":["trace[1759682075] 'agreement among raft nodes before linearized reading' (duration: 1.17751169s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:07.747696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:06.569693Z","time spent":"1.177994132s","remote":"127.0.0.1:49642","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-21T17:12:07.906918Z","caller":"traceutil/trace.go:171","msg":"trace[1200845221] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"137.452404ms","start":"2026-02-21T17:12:07.769434Z","end":"2026-02-21T17:12:07.906886Z","steps":["trace[1200845221] 'process raft request' (duration: 100.810975ms)","trace[1200845221] 'compare' (duration: 36.500766ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:12:08.492241Z","caller":"traceutil/trace.go:171","msg":"trace[2108190941] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"547.268548ms","start":"2026-02-21T17:12:07.944942Z","end":"2026-02-21T17:12:08.492211Z","steps":["trace[2108190941] 'process raft request' (duration: 447.848855ms)","trace[2108190941] 'compare' (duration: 99.107066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:12:08.492395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:07.944922Z","time spent":"547.399241ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:12:08.647289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.145578ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T17:12:09.116015Z","caller":"traceutil/trace.go:171","msg":"trace[466600982] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"190.822786ms","start":"2026-02-21T17:12:08.925167Z","end":"2026-02-21T17:12:09.11599Z","steps":["trace[466600982] 'process raft request' (duration: 190.647132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:10.059473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.634109ms","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-21T17:12:10.059572Z","caller":"traceutil/trace.go:171","msg":"trace[206955973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"305.841323ms","start":"2026-02-21T17:12:09.753704Z","end":"2026-02-21T17:12:10.059545Z","steps":["trace[206955973] 'range keys from in-memory index tree' (duration: 305.402984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:10.059629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:09.753682Z","time spent":"305.931516ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T17:12:10.059645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.498968ms","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-21T17:12:10.059706Z","caller":"traceutil/trace.go:171","msg":"trace[1702824176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"138.595981ms","start":"2026-02-21T17:12:09.921096Z","end":"2026-02-21T17:12:10.059692Z","steps":["trace[1702824176] 'range keys from in-memory index tree' (duration: 138.344585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:10.059784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.451257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T17:12:10.05986Z","caller":"traceutil/trace.go:171","msg":"trace[495896902] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1027; }","duration":"650.57255ms","start":"2026-02-21T17:12:09.409265Z","end":"2026-02-21T17:12:10.059838Z","steps":["trace[495896902] 'count revisions from in-memory index tree' (duration: 650.352625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:10.059925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:09.409234Z","time spent":"650.674923ms","remote":"127.0.0.1:49580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-21T17:12:10.059996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.278168ms","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-21T17:12:10.060061Z","caller":"traceutil/trace.go:171","msg":"trace[1797545812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"307.356999ms","start":"2026-02-21T17:12:09.752686Z","end":"2026-02-21T17:12:10.060043Z","steps":["trace[1797545812] 'range keys from in-memory index tree' (duration: 307.158814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:10.060112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:09.752679Z","time spent":"307.42019ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T17:12:10.060345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.113718ms","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-21T17:12:10.06047Z","caller":"traceutil/trace.go:171","msg":"trace[681013476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"308.310582ms","start":"2026-02-21T17:12:09.752134Z","end":"2026-02-21T17:12:10.060444Z","steps":["trace[681013476] 'range keys from in-memory index tree' (duration: 307.590524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:10.060583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:09.752113Z","time spent":"308.453955ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-21T17:12:12.836655Z","caller":"traceutil/trace.go:171","msg":"trace[1967117411] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"239.117512ms","start":"2026-02-21T17:12:12.597513Z","end":"2026-02-21T17:12:12.83663Z","steps":["trace[1967117411] 'process raft request' (duration: 239.061021ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:12:12.837003Z","caller":"traceutil/trace.go:171","msg":"trace[1144920126] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"322.682268ms","start":"2026-02-21T17:12:12.514292Z","end":"2026-02-21T17:12:12.836974Z","steps":["trace[1144920126] 'process raft request' (duration: 321.307796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:12.837127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:12.514273Z","time spent":"322.771351ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:15.194006Z","caller":"traceutil/trace.go:171","msg":"trace[766551285] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"348.656957ms","start":"2026-02-21T17:12:14.845323Z","end":"2026-02-21T17:12:15.19398Z","steps":["trace[766551285] 'process raft request' (duration: 348.507514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:15.19416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:14.845312Z","time spent":"348.76319ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T17:12:15.195152Z","caller":"traceutil/trace.go:171","msg":"trace[721154022] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"347.719947ms","start":"2026-02-21T17:12:14.847402Z","end":"2026-02-21T17:12:15.195122Z","steps":["trace[721154022] 'process raft request' (duration: 347.219754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:12:15.195273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:12:14.847394Z","time spent":"347.826189ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-21T17:12:17.147664Z","caller":"traceutil/trace.go:171","msg":"trace[1633692554] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"155.015402ms","start":"2026-02-21T17:12:16.992616Z","end":"2026-02-21T17:12:17.147631Z","steps":["trace[1633692554] 'process raft request' (duration: 154.528329ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:15:22.772175Z","caller":"traceutil/trace.go:171","msg":"trace[2141736203] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"116.146346ms","start":"2026-02-21T17:15:22.655974Z","end":"2026-02-21T17:15:22.772121Z","steps":["trace[2141736203] 'process raft request' (duration: 115.86317ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:15:25.400612Z","caller":"traceutil/trace.go:171","msg":"trace[1595660795] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"180.794646ms","start":"2026-02-21T17:15:25.219797Z","end":"2026-02-21T17:15:25.400592Z","steps":["trace[1595660795] 'process raft request' (duration: 180.680933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:15:25.576304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.203672ms","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-21T17:15:25.576377Z","caller":"traceutil/trace.go:171","msg":"trace[202384244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1876; }","duration":"104.327675ms","start":"2026-02-21T17:15:25.472032Z","end":"2026-02-21T17:15:25.57636Z","steps":["trace[202384244] 'range keys from in-memory index tree' (duration: 104.043298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:15:25.816302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.51772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:15:25.816394Z","caller":"traceutil/trace.go:171","msg":"trace[436543593] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"124.833566ms","start":"2026-02-21T17:15:25.691541Z","end":"2026-02-21T17:15:25.816375Z","steps":["trace[436543593] 'read index received' (duration: 14.082372ms)","trace[436543593] 'applied index is now lower than readState.Index' (duration: 110.750114ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:15:25.816423Z","caller":"traceutil/trace.go:171","msg":"trace[1423134953] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"148.209227ms","start":"2026-02-21T17:15:25.668174Z","end":"2026-02-21T17:15:25.816383Z","steps":["trace[1423134953] 'process raft request' (duration: 37.532664ms)","trace[1423134953] 'compare' (duration: 110.410166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:15:25.816576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.032161ms","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-21T17:15:25.816612Z","caller":"traceutil/trace.go:171","msg":"trace[1981545186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1879; }","duration":"125.109143ms","start":"2026-02-21T17:15:25.691493Z","end":"2026-02-21T17:15:25.816602Z","steps":["trace[1981545186] 'agreement among raft nodes before linearized reading' (duration: 124.927068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:15:32.693833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.662106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T17:15:32.693928Z","caller":"traceutil/trace.go:171","msg":"trace[278612087] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"144.236052ms","start":"2026-02-21T17:15:32.549676Z","end":"2026-02-21T17:15:32.693912Z","steps":["trace[278612087] 'read index received' (duration: 1.395302ms)","trace[278612087] 'applied index is now lower than readState.Index' (duration: 142.83996ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:15:32.693998Z","caller":"traceutil/trace.go:171","msg":"trace[586127037] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"194.779731ms","start":"2026-02-21T17:15:32.499174Z","end":"2026-02-21T17:15:32.693953Z","steps":["trace[586127037] 'process raft request' (duration: 51.91409ms)","trace[586127037] 'compare' (duration: 142.551574ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T17:15:32.69406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.389126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-21T17:15:32.694083Z","caller":"traceutil/trace.go:171","msg":"trace[1476610355] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:1910; }","duration":"144.451038ms","start":"2026-02-21T17:15:32.549626Z","end":"2026-02-21T17:15:32.694077Z","steps":["trace[1476610355] 'agreement among raft nodes before linearized reading' (duration: 144.321735ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:15:33.193613Z","caller":"traceutil/trace.go:171","msg":"trace[221329229] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"125.37051ms","start":"2026-02-21T17:15:33.068211Z","end":"2026-02-21T17:15:33.193582Z","steps":["trace[221329229] 'process raft request' (duration: 125.055121ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:15:33.512449Z","caller":"traceutil/trace.go:171","msg":"trace[2134798888] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"160.668059ms","start":"2026-02-21T17:15:33.351735Z","end":"2026-02-21T17:15:33.512403Z","steps":["trace[2134798888] 'process raft request' (duration: 159.713757ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:15:33.9348Z","caller":"traceutil/trace.go:171","msg":"trace[1896733758] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"270.111804ms","start":"2026-02-21T17:15:33.664661Z","end":"2026-02-21T17:15:33.934773Z","steps":["trace[1896733758] 'read index received' (duration: 269.658173ms)","trace[1896733758] 'applied index is now lower than readState.Index' (duration: 452.261µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:15:33.934872Z","caller":"traceutil/trace.go:171","msg":"trace[1718484260] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"416.782434ms","start":"2026-02-21T17:15:33.518057Z","end":"2026-02-21T17:15:33.934839Z","steps":["trace[1718484260] 'process raft request' (duration: 416.548329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:15:33.935029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T17:15:33.51803Z","time spent":"416.912918ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T17:15:33.935383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.443021ms","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-21T17:15:33.935477Z","caller":"traceutil/trace.go:171","msg":"trace[1670572166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1915; }","duration":"270.861141ms","start":"2026-02-21T17:15:33.664594Z","end":"2026-02-21T17:15:33.935455Z","steps":["trace[1670572166] 'agreement among raft nodes before linearized reading' (duration: 270.312248ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T17:15:52.1967Z","caller":"traceutil/trace.go:171","msg":"trace[796129332] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2087; }","duration":"106.377832ms","start":"2026-02-21T17:15:52.090302Z","end":"2026-02-21T17:15:52.19668Z","steps":["trace[796129332] 'read index received' (duration: 65.967432ms)","trace[796129332] 'applied index is now lower than readState.Index' (duration: 40.40973ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T17:15:52.196785Z","caller":"traceutil/trace.go:171","msg":"trace[907538679] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"106.533736ms","start":"2026-02-21T17:15:52.090215Z","end":"2026-02-21T17:15:52.196748Z","steps":["trace[907538679] 'process raft request' (duration: 106.370412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T17:15:52.196923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.600857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9324"} {"level":"info","ts":"2026-02-21T17:15:52.197002Z","caller":"traceutil/trace.go:171","msg":"trace[231003546] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1996; }","duration":"106.70462ms","start":"2026-02-21T17:15:52.090278Z","end":"2026-02-21T17:15:52.196982Z","steps":["trace[231003546] 'agreement among raft nodes before linearized reading' (duration: 106.474815ms)"],"step_count":1}