{"level":"warn","ts":"2026-02-05T22:13:32.267777Z","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-05T22:13:32.267849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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://162.253.55.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-05T22:13:32.267911Z","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-05T22:13:32.267919Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-02-05T22:13:32.267936Z","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-05T22:13:32.268489Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-02-05T22:13:32.268561Z","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":2,"max-cpu-available":2,"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://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-05T22:13:32.279437Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.346831ms"} {"level":"info","ts":"2026-02-05T22:13:32.336303Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-02-05T22:13:32.336453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T22:13:32.336501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-02-05T22:13:32.336529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T22:13:32.33655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-02-05T22:13:32.336626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-02-05T22:13:32.345502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T22:13:32.347923Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T22:13:32.350996Z","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-05T22:13:32.354224Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T22:13:32.35876Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T22:13:32.358982Z","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-05T22:13:32.359015Z","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-05T22:13:32.359025Z","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-05T22:13:32.35927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-02-05T22:13:32.359413Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-02-05T22:13:32.366812Z","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-05T22:13:32.367909Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T22:13:32.369658Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T22:13:32.367765Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-02-05T22:13:32.371065Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-02-05T22:13:32.837323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T22:13:32.837363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T22:13:32.837382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-02-05T22:13:32.837397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-02-05T22:13:32.837402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-02-05T22:13:32.83741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-02-05T22:13:32.837417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-02-05T22:13:32.844864Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T22:13:32.844976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:13:32.845227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:32.845969Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:13:32.847606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-02-05T22:13:32.856737Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T22:13:32.856758Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T22:13:32.858149Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:32.858229Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:32.85825Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:32.858484Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T22:13:38.858579Z","caller":"traceutil/trace.go:171","msg":"trace[1212344945] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"154.943491ms","start":"2026-02-05T22:13:38.703614Z","end":"2026-02-05T22:13:38.858557Z","steps":["trace[1212344945] 'read index received' (duration: 135.796403ms)","trace[1212344945] 'applied index is now lower than readState.Index' (duration: 19.146458ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:38.858746Z","caller":"traceutil/trace.go:171","msg":"trace[1847568377] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"161.628889ms","start":"2026-02-05T22:13:38.697109Z","end":"2026-02-05T22:13:38.858738Z","steps":["trace[1847568377] 'process raft request' (duration: 142.46419ms)","trace[1847568377] 'compare' (duration: 18.604744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:38.85882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.746859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5875"} {"level":"info","ts":"2026-02-05T22:13:38.858847Z","caller":"traceutil/trace.go:171","msg":"trace[30321184] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:257; }","duration":"174.81426ms","start":"2026-02-05T22:13:38.684025Z","end":"2026-02-05T22:13:38.858839Z","steps":["trace[30321184] 'agreement among raft nodes before linearized reading' (duration: 174.755108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.894938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.618113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:38.895022Z","caller":"traceutil/trace.go:171","msg":"trace[461693637] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:258; }","duration":"186.744807ms","start":"2026-02-05T22:13:38.708257Z","end":"2026-02-05T22:13:38.895002Z","steps":["trace[461693637] 'agreement among raft nodes before linearized reading' (duration: 186.597103ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:38.89521Z","caller":"traceutil/trace.go:171","msg":"trace[1879035818] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"185.280758ms","start":"2026-02-05T22:13:38.709921Z","end":"2026-02-05T22:13:38.895202Z","steps":["trace[1879035818] 'process raft request' (duration: 184.780645ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:44.037888Z","caller":"traceutil/trace.go:171","msg":"trace[769391909] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"106.957994ms","start":"2026-02-05T22:13:43.930903Z","end":"2026-02-05T22:13:44.037861Z","steps":["trace[769391909] 'process raft request' (duration: 58.114576ms)","trace[769391909] 'compare' (duration: 48.454008ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:44.166389Z","caller":"traceutil/trace.go:171","msg":"trace[898440705] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"106.889982ms","start":"2026-02-05T22:13:44.059474Z","end":"2026-02-05T22:13:44.166364Z","steps":["trace[898440705] 'process raft request' (duration: 98.627622ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:44.166449Z","caller":"traceutil/trace.go:171","msg":"trace[1399325561] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"103.558463ms","start":"2026-02-05T22:13:44.062856Z","end":"2026-02-05T22:13:44.166414Z","steps":["trace[1399325561] 'process raft request' (duration: 103.205034ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:44.291943Z","caller":"traceutil/trace.go:171","msg":"trace[1771174866] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"106.860841ms","start":"2026-02-05T22:13:44.185045Z","end":"2026-02-05T22:13:44.291906Z","steps":["trace[1771174866] 'process raft request' (duration: 90.554428ms)","trace[1771174866] 'compare' (duration: 15.868062ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:44.292073Z","caller":"traceutil/trace.go:171","msg":"trace[1042320810] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"106.973025ms","start":"2026-02-05T22:13:44.185095Z","end":"2026-02-05T22:13:44.292068Z","steps":["trace[1042320810] 'process raft request' (duration: 106.576584ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:44.292212Z","caller":"traceutil/trace.go:171","msg":"trace[2081438463] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"105.967738ms","start":"2026-02-05T22:13:44.186236Z","end":"2026-02-05T22:13:44.292203Z","steps":["trace[2081438463] 'process raft request' (duration: 105.476325ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:44.525778Z","caller":"traceutil/trace.go:171","msg":"trace[1679386687] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"130.621513ms","start":"2026-02-05T22:13:44.395129Z","end":"2026-02-05T22:13:44.52575Z","steps":["trace[1679386687] 'process raft request' (duration: 130.447928ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:46.795596Z","caller":"traceutil/trace.go:171","msg":"trace[1500931655] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"126.590277ms","start":"2026-02-05T22:13:46.668979Z","end":"2026-02-05T22:13:46.795569Z","steps":["trace[1500931655] 'process raft request' (duration: 126.191406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:48.169487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.614162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:13:48.169683Z","caller":"traceutil/trace.go:171","msg":"trace[1768830172] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:377; }","duration":"1.223345361s","start":"2026-02-05T22:13:46.946322Z","end":"2026-02-05T22:13:48.169667Z","steps":["trace[1768830172] 'read index received' (duration: 231.756473ms)","trace[1768830172] 'applied index is now lower than readState.Index' (duration: 991.588048ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:48.169746Z","caller":"traceutil/trace.go:171","msg":"trace[460145125] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.365776228s","start":"2026-02-05T22:13:46.803962Z","end":"2026-02-05T22:13:48.169738Z","steps":["trace[460145125] 'process raft request' (duration: 1.365624384s)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:48.169767Z","caller":"traceutil/trace.go:171","msg":"trace[633713284] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.366509598s","start":"2026-02-05T22:13:46.803233Z","end":"2026-02-05T22:13:48.169742Z","steps":["trace[633713284] 'process raft request' (duration: 374.91692ms)","trace[633713284] 'compare' (duration: 990.495699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:48.169802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:46.803949Z","time spent":"1.365820058s","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:48.16988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:46.803212Z","time spent":"1.36660739s","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:13:48.169981Z","caller":"traceutil/trace.go:171","msg":"trace[1635023412] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"287.968488ms","start":"2026-02-05T22:13:47.882006Z","end":"2026-02-05T22:13:48.169975Z","steps":["trace[1635023412] 'process raft request' (duration: 287.636009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:48.170092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223765902s","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-05T22:13:48.170123Z","caller":"traceutil/trace.go:171","msg":"trace[563490688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"1.223822163s","start":"2026-02-05T22:13:46.94629Z","end":"2026-02-05T22:13:48.170112Z","steps":["trace[563490688] 'agreement among raft nodes before linearized reading' (duration: 1.223721171s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:48.170153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:46.946276Z","time spent":"1.223871494s","remote":"127.0.0.1:44670","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-05T22:13:48.210968Z","caller":"traceutil/trace.go:171","msg":"trace[513295793] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"280.364146ms","start":"2026-02-05T22:13:47.930577Z","end":"2026-02-05T22:13:48.210941Z","steps":["trace[513295793] 'process raft request' (duration: 280.259313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:48.21121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.989933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4729"} {"level":"info","ts":"2026-02-05T22:13:48.211285Z","caller":"traceutil/trace.go:171","msg":"trace[62838798] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:369; }","duration":"624.112956ms","start":"2026-02-05T22:13:47.587154Z","end":"2026-02-05T22:13:48.211267Z","steps":["trace[62838798] 'agreement among raft nodes before linearized reading' (duration: 623.920771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:48.211324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:47.587137Z","time spent":"624.179307ms","remote":"127.0.0.1:44574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4752,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-05T22:14:05.228207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.720019ms","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-05T22:14:05.228287Z","caller":"traceutil/trace.go:171","msg":"trace[182680475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"308.864404ms","start":"2026-02-05T22:14:04.919405Z","end":"2026-02-05T22:14:05.22827Z","steps":["trace[182680475] 'range keys from in-memory index tree' (duration: 308.539174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:05.22834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:04.919388Z","time spent":"308.941006ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:14:06.884943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.656208ms","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-05T22:14:06.885013Z","caller":"traceutil/trace.go:171","msg":"trace[977945727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"396.75177ms","start":"2026-02-05T22:14:06.488244Z","end":"2026-02-05T22:14:06.884996Z","steps":["trace[977945727] 'range keys from in-memory index tree' (duration: 396.605637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:06.88504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:06.488233Z","time spent":"396.799851ms","remote":"127.0.0.1:44670","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-05T22:14:06.885215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.047775ms","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-05T22:14:06.885234Z","caller":"traceutil/trace.go:171","msg":"trace[894579507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"492.088895ms","start":"2026-02-05T22:14:06.393141Z","end":"2026-02-05T22:14:06.88523Z","steps":["trace[894579507] 'range keys from in-memory index tree' (duration: 492.002994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:06.885246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:06.393129Z","time spent":"492.113946ms","remote":"127.0.0.1:44670","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-05T22:14:06.885307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.492866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:06.885318Z","caller":"traceutil/trace.go:171","msg":"trace[893010756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"439.524158ms","start":"2026-02-05T22:14:06.445791Z","end":"2026-02-05T22:14:06.885315Z","steps":["trace[893010756] 'range keys from in-memory index tree' (duration: 439.463976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:06.885329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:06.445779Z","time spent":"439.547208ms","remote":"127.0.0.1:44348","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-05T22:14:08.878615Z","caller":"traceutil/trace.go:171","msg":"trace[849993485] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"585.5319ms","start":"2026-02-05T22:14:08.293061Z","end":"2026-02-05T22:14:08.878593Z","steps":["trace[849993485] 'process raft request' (duration: 585.384016ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:08.878617Z","caller":"traceutil/trace.go:171","msg":"trace[1464439366] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:499; }","duration":"429.863481ms","start":"2026-02-05T22:14:08.448724Z","end":"2026-02-05T22:14:08.878588Z","steps":["trace[1464439366] 'read index received' (duration: 429.850091ms)","trace[1464439366] 'applied index is now lower than readState.Index' (duration: 11.29µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:08.878751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:08.29305Z","time spent":"585.627152ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:08.878807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.056036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:08.878841Z","caller":"traceutil/trace.go:171","msg":"trace[1521943413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"430.143358ms","start":"2026-02-05T22:14:08.448687Z","end":"2026-02-05T22:14:08.87883Z","steps":["trace[1521943413] 'agreement among raft nodes before linearized reading' (duration: 430.024055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:08.878879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:08.448669Z","time spent":"430.19973ms","remote":"127.0.0.1:44376","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-05T22:14:08.988274Z","caller":"traceutil/trace.go:171","msg":"trace[615371225] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"481.310409ms","start":"2026-02-05T22:14:08.506935Z","end":"2026-02-05T22:14:08.988246Z","steps":["trace[615371225] 'process raft request' (duration: 468.914209ms)","trace[615371225] 'compare' (duration: 12.180085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:08.988449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:08.506919Z","time spent":"481.456823ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:13.391336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923328329,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:14:13.640447Z","caller":"traceutil/trace.go:171","msg":"trace[1508639729] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"749.50494ms","start":"2026-02-05T22:14:12.890919Z","end":"2026-02-05T22:14:13.640424Z","steps":["trace[1508639729] 'read index received' (duration: 749.244094ms)","trace[1508639729] 'applied index is now lower than readState.Index' (duration: 259.996µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:13.64056Z","caller":"traceutil/trace.go:171","msg":"trace[1156940138] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"889.920254ms","start":"2026-02-05T22:14:12.750631Z","end":"2026-02-05T22:14:13.640551Z","steps":["trace[1156940138] 'process raft request' (duration: 889.595355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:13.640696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:12.750609Z","time spent":"889.982155ms","remote":"127.0.0.1:44574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:13.640923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.012564ms","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-05T22:14:13.640963Z","caller":"traceutil/trace.go:171","msg":"trace[197557402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"750.083746ms","start":"2026-02-05T22:14:12.890869Z","end":"2026-02-05T22:14:13.640953Z","steps":["trace[197557402] 'agreement among raft nodes before linearized reading' (duration: 750.008534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:13.640997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:12.890852Z","time spent":"750.138317ms","remote":"127.0.0.1:44670","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-05T22:14:13.642057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.048913ms","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-05T22:14:13.645823Z","caller":"traceutil/trace.go:171","msg":"trace[1262213147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"384.848363ms","start":"2026-02-05T22:14:13.260955Z","end":"2026-02-05T22:14:13.645803Z","steps":["trace[1262213147] 'agreement among raft nodes before linearized reading' (duration: 380.751495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:13.646493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.260942Z","time spent":"385.532792ms","remote":"127.0.0.1:44670","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-05T22:14:13.646195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.765609ms","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-05T22:14:13.64758Z","caller":"traceutil/trace.go:171","msg":"trace[2025384514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"633.187758ms","start":"2026-02-05T22:14:13.014376Z","end":"2026-02-05T22:14:13.647563Z","steps":["trace[2025384514] 'agreement among raft nodes before linearized reading' (duration: 626.744347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:13.647889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.014357Z","time spent":"633.515026ms","remote":"127.0.0.1:44670","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-05T22:14:14.079381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.526847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:14.07943Z","caller":"traceutil/trace.go:171","msg":"trace[1689613999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"630.582138ms","start":"2026-02-05T22:14:13.448836Z","end":"2026-02-05T22:14:14.079418Z","steps":["trace[1689613999] 'agreement among raft nodes before linearized reading' (duration: 198.420237ms)","trace[1689613999] 'range keys from in-memory index tree' (duration: 431.890544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:14.079462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.448756Z","time spent":"630.701281ms","remote":"127.0.0.1:44348","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-05T22:14:14.079673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065235636s","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-05T22:14:14.079713Z","caller":"traceutil/trace.go:171","msg":"trace[1664384205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"1.065308417s","start":"2026-02-05T22:14:13.014394Z","end":"2026-02-05T22:14:14.079702Z","steps":["trace[1664384205] 'agreement among raft nodes before linearized reading' (duration: 634.521782ms)","trace[1664384205] 'range keys from in-memory index tree' (duration: 430.664072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:14.079748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.014374Z","time spent":"1.065366279s","remote":"127.0.0.1:44670","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-05T22:14:14.080312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.589541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:14.080475Z","caller":"traceutil/trace.go:171","msg":"trace[2046355351] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"423.594013ms","start":"2026-02-05T22:14:13.656871Z","end":"2026-02-05T22:14:14.080465Z","steps":["trace[2046355351] 'process raft request' (duration: 423.561963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.080535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.656847Z","time spent":"423.660706ms","remote":"127.0.0.1:44670","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-05T22:14:14.080835Z","caller":"traceutil/trace.go:171","msg":"trace[1912358391] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"427.657402ms","start":"2026-02-05T22:14:13.653165Z","end":"2026-02-05T22:14:14.080822Z","steps":["trace[1912358391] 'process raft request' (duration: 102.502196ms)","trace[1912358391] 'compare' (duration: 324.304393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:14.080903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.65315Z","time spent":"427.724394ms","remote":"127.0.0.1:44670","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-05T22:14:14.081015Z","caller":"traceutil/trace.go:171","msg":"trace[397678962] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"427.775666ms","start":"2026-02-05T22:14:13.653231Z","end":"2026-02-05T22:14:14.081006Z","steps":["trace[397678962] 'process raft request' (duration: 427.151518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.081066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:13.653227Z","time spent":"427.815507ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:14.949856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923328337,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:14:14.96299Z","caller":"traceutil/trace.go:171","msg":"trace[1386424456] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"513.36137ms","start":"2026-02-05T22:14:14.449611Z","end":"2026-02-05T22:14:14.962973Z","steps":["trace[1386424456] 'read index received' (duration: 509.217321ms)","trace[1386424456] 'applied index is now lower than readState.Index' (duration: 4.143329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:14.963065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.451204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:14.963086Z","caller":"traceutil/trace.go:171","msg":"trace[2086438933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"513.514395ms","start":"2026-02-05T22:14:14.449565Z","end":"2026-02-05T22:14:14.96308Z","steps":["trace[2086438933] 'agreement among raft nodes before linearized reading' (duration: 513.463864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.963107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.449533Z","time spent":"513.569207ms","remote":"127.0.0.1:44348","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-05T22:14:14.963093Z","caller":"traceutil/trace.go:171","msg":"trace[1378656541] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"875.198393ms","start":"2026-02-05T22:14:14.087875Z","end":"2026-02-05T22:14:14.963073Z","steps":["trace[1378656541] 'process raft request' (duration: 870.916939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.963189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.087868Z","time spent":"875.266845ms","remote":"127.0.0.1:44670","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-05T22:14:15.861608Z","caller":"traceutil/trace.go:171","msg":"trace[893505523] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"128.657741ms","start":"2026-02-05T22:14:15.732928Z","end":"2026-02-05T22:14:15.861585Z","steps":["trace[893505523] 'process raft request' (duration: 128.519208ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:15.918829Z","caller":"traceutil/trace.go:171","msg":"trace[152879327] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"179.199215ms","start":"2026-02-05T22:14:15.739615Z","end":"2026-02-05T22:14:15.918814Z","steps":["trace[152879327] 'process raft request' (duration: 179.084212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:17.280358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.670522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:17.280441Z","caller":"traceutil/trace.go:171","msg":"trace[1120643777] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"120.577566ms","start":"2026-02-05T22:14:17.159849Z","end":"2026-02-05T22:14:17.280426Z","steps":["trace[1120643777] 'read index received' (duration: 30.581µs)","trace[1120643777] 'applied index is now lower than readState.Index' (duration: 120.545985ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:17.280573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.72063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T22:14:17.280598Z","caller":"traceutil/trace.go:171","msg":"trace[585853336] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:516; }","duration":"120.772172ms","start":"2026-02-05T22:14:17.159817Z","end":"2026-02-05T22:14:17.280589Z","steps":["trace[585853336] 'agreement among raft nodes before linearized reading' (duration: 120.650108ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:17.280804Z","caller":"traceutil/trace.go:171","msg":"trace[1555098904] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"312.016537ms","start":"2026-02-05T22:14:16.968776Z","end":"2026-02-05T22:14:17.280793Z","steps":["trace[1555098904] 'process raft request' (duration: 109.852892ms)","trace[1555098904] 'compare' (duration: 201.556429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:17.280876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:16.968765Z","time spent":"312.081799ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:20.868911Z","caller":"traceutil/trace.go:171","msg":"trace[814617612] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"111.243719ms","start":"2026-02-05T22:14:20.757649Z","end":"2026-02-05T22:14:20.868893Z","steps":["trace[814617612] 'process raft request' (duration: 74.897522ms)","trace[814617612] 'compare' (duration: 35.537965ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:21.07781Z","caller":"traceutil/trace.go:171","msg":"trace[1508588232] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"148.52455ms","start":"2026-02-05T22:14:20.929207Z","end":"2026-02-05T22:14:21.077732Z","steps":["trace[1508588232] 'process raft request' (duration: 114.730161ms)","trace[1508588232] 'compare' (duration: 33.238874ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:21.078983Z","caller":"traceutil/trace.go:171","msg":"trace[1317030646] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"148.972821ms","start":"2026-02-05T22:14:20.929993Z","end":"2026-02-05T22:14:21.078966Z","steps":["trace[1317030646] 'read index received' (duration: 113.906978ms)","trace[1317030646] 'applied index is now lower than readState.Index' (duration: 35.064183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:21.081225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.198109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-05T22:14:21.081341Z","caller":"traceutil/trace.go:171","msg":"trace[626379816] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:577; }","duration":"151.380917ms","start":"2026-02-05T22:14:20.929941Z","end":"2026-02-05T22:14:21.081322Z","steps":["trace[626379816] 'agreement among raft nodes before linearized reading' (duration: 149.107886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:21.528646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.37516ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T22:14:21.529006Z","caller":"traceutil/trace.go:171","msg":"trace[567398545] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"100.05432ms","start":"2026-02-05T22:14:21.428941Z","end":"2026-02-05T22:14:21.528995Z","steps":["trace[567398545] 'process raft request' (duration: 99.806524ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:26.755305Z","caller":"traceutil/trace.go:171","msg":"trace[481830950] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"409.53113ms","start":"2026-02-05T22:14:26.345757Z","end":"2026-02-05T22:14:26.755288Z","steps":["trace[481830950] 'read index received' (duration: 376.115551ms)","trace[481830950] 'applied index is now lower than readState.Index' (duration: 33.412729ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:26.75546Z","caller":"traceutil/trace.go:171","msg":"trace[1438259702] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"506.427957ms","start":"2026-02-05T22:14:26.249026Z","end":"2026-02-05T22:14:26.755454Z","steps":["trace[1438259702] 'process raft request' (duration: 472.870475ms)","trace[1438259702] 'compare' (duration: 33.066849ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:26.755525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.249017Z","time spent":"506.462628ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:26.75566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.902109ms","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-05T22:14:26.75568Z","caller":"traceutil/trace.go:171","msg":"trace[1770061641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"409.944151ms","start":"2026-02-05T22:14:26.34573Z","end":"2026-02-05T22:14:26.755675Z","steps":["trace[1770061641] 'agreement among raft nodes before linearized reading' (duration: 409.875969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:26.755695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.345717Z","time spent":"409.973641ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:14:26.75578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.240263ms","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-05T22:14:26.755799Z","caller":"traceutil/trace.go:171","msg":"trace[1045919242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"406.290664ms","start":"2026-02-05T22:14:26.349503Z","end":"2026-02-05T22:14:26.755794Z","steps":["trace[1045919242] 'agreement among raft nodes before linearized reading' (duration: 406.246983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:26.755811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.349487Z","time spent":"406.321404ms","remote":"127.0.0.1:44670","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-05T22:14:26.755897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.599453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:26.755916Z","caller":"traceutil/trace.go:171","msg":"trace[245128150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"303.648604ms","start":"2026-02-05T22:14:26.452262Z","end":"2026-02-05T22:14:26.755911Z","steps":["trace[245128150] 'agreement among raft nodes before linearized reading' (duration: 303.624023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:26.755927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.45225Z","time spent":"303.675314ms","remote":"127.0.0.1:44348","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-05T22:14:27.203435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.382385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:27.203534Z","caller":"traceutil/trace.go:171","msg":"trace[1494183372] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"439.363574ms","start":"2026-02-05T22:14:26.76416Z","end":"2026-02-05T22:14:27.203524Z","steps":["trace[1494183372] 'process raft request' (duration: 439.333403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.203566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.76415Z","time spent":"439.401944ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:27.203667Z","caller":"traceutil/trace.go:171","msg":"trace[804968097] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"443.842912ms","start":"2026-02-05T22:14:26.759818Z","end":"2026-02-05T22:14:27.203661Z","steps":["trace[804968097] 'process raft request' (duration: 283.17762ms)","trace[804968097] 'compare' (duration: 160.326553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:27.20369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.7598Z","time spent":"443.878683ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:27.760939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.271797ms","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-05T22:14:27.761034Z","caller":"traceutil/trace.go:171","msg":"trace[1322521836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:611; }","duration":"173.402361ms","start":"2026-02-05T22:14:27.587605Z","end":"2026-02-05T22:14:27.761007Z","steps":["trace[1322521836] 'range keys from in-memory index tree' (duration: 173.103443ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.927986Z","caller":"traceutil/trace.go:171","msg":"trace[1108045961] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"154.224942ms","start":"2026-02-05T22:14:27.773746Z","end":"2026-02-05T22:14:27.927971Z","steps":["trace[1108045961] 'process raft request' (duration: 154.116329ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:30.101577Z","caller":"traceutil/trace.go:171","msg":"trace[1353545082] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"168.841879ms","start":"2026-02-05T22:14:29.932707Z","end":"2026-02-05T22:14:30.101549Z","steps":["trace[1353545082] 'process raft request' (duration: 168.736986ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:32.961719Z","caller":"traceutil/trace.go:171","msg":"trace[319155264] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"171.11408ms","start":"2026-02-05T22:14:32.790578Z","end":"2026-02-05T22:14:32.961692Z","steps":["trace[319155264] 'read index received' (duration: 170.963326ms)","trace[319155264] 'applied index is now lower than readState.Index' (duration: 150.104µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:32.961886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.289245ms","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-05T22:14:32.961908Z","caller":"traceutil/trace.go:171","msg":"trace[1002971737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"171.356847ms","start":"2026-02-05T22:14:32.790544Z","end":"2026-02-05T22:14:32.961901Z","steps":["trace[1002971737] 'agreement among raft nodes before linearized reading' (duration: 171.236644ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:32.962103Z","caller":"traceutil/trace.go:171","msg":"trace[1539323119] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"397.270034ms","start":"2026-02-05T22:14:32.564825Z","end":"2026-02-05T22:14:32.962095Z","steps":["trace[1539323119] 'process raft request' (duration: 396.7564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:32.962165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:32.56481Z","time spent":"397.313286ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:14:33.396696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.332721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:33.39678Z","caller":"traceutil/trace.go:171","msg":"trace[1605713219] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"167.771272ms","start":"2026-02-05T22:14:33.228998Z","end":"2026-02-05T22:14:33.39677Z","steps":["trace[1605713219] 'read index received' (duration: 27.831µs)","trace[1605713219] 'applied index is now lower than readState.Index' (duration: 167.74251ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:33.396909Z","caller":"traceutil/trace.go:171","msg":"trace[1335201733] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"429.836341ms","start":"2026-02-05T22:14:32.967065Z","end":"2026-02-05T22:14:33.396901Z","steps":["trace[1335201733] 'process raft request' (duration: 160.216842ms)","trace[1335201733] 'compare' (duration: 269.159727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:33.396947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:32.967051Z","time spent":"429.875532ms","remote":"127.0.0.1:44670","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-05T22:14:33.397098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.09808ms","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-05T22:14:33.397116Z","caller":"traceutil/trace.go:171","msg":"trace[691000775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"168.155361ms","start":"2026-02-05T22:14:33.228954Z","end":"2026-02-05T22:14:33.39711Z","steps":["trace[691000775] 'agreement among raft nodes before linearized reading' (duration: 168.08473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:33.397238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.136841ms","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-05T22:14:33.397255Z","caller":"traceutil/trace.go:171","msg":"trace[1449122833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"168.163511ms","start":"2026-02-05T22:14:33.229086Z","end":"2026-02-05T22:14:33.39725Z","steps":["trace[1449122833] 'agreement among raft nodes before linearized reading' (duration: 168.11617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:33.880177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.484078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:33.880333Z","caller":"traceutil/trace.go:171","msg":"trace[65133103] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"351.65157ms","start":"2026-02-05T22:14:33.528672Z","end":"2026-02-05T22:14:33.880323Z","steps":["trace[65133103] 'read index received' (duration: 7.16224ms)","trace[65133103] 'applied index is now lower than readState.Index' (duration: 344.48864ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:33.880343Z","caller":"traceutil/trace.go:171","msg":"trace[488843801] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"479.457149ms","start":"2026-02-05T22:14:33.400863Z","end":"2026-02-05T22:14:33.88032Z","steps":["trace[488843801] 'process raft request' (duration: 134.962779ms)","trace[488843801] 'compare' (duration: 339.336773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:33.880451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:33.400849Z","time spent":"479.543722ms","remote":"127.0.0.1:44670","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-05T22:14:33.880487Z","caller":"traceutil/trace.go:171","msg":"trace[1265596891] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"478.921515ms","start":"2026-02-05T22:14:33.40156Z","end":"2026-02-05T22:14:33.880481Z","steps":["trace[1265596891] 'process raft request' (duration: 478.697669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:33.880536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:33.401542Z","time spent":"478.963376ms","remote":"127.0.0.1:44670","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-05T22:14:33.880737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.079801ms","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-05T22:14:33.880766Z","caller":"traceutil/trace.go:171","msg":"trace[988174083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"352.168254ms","start":"2026-02-05T22:14:33.528588Z","end":"2026-02-05T22:14:33.880757Z","steps":["trace[988174083] 'agreement among raft nodes before linearized reading' (duration: 352.076631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:33.880786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:33.52857Z","time spent":"352.212446ms","remote":"127.0.0.1:44670","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-05T22:14:33.88082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.340491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:14:33.88086Z","caller":"traceutil/trace.go:171","msg":"trace[767046299] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:643; }","duration":"199.406563ms","start":"2026-02-05T22:14:33.681445Z","end":"2026-02-05T22:14:33.880852Z","steps":["trace[767046299] 'agreement among raft nodes before linearized reading' (duration: 199.349662ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:34.593707Z","caller":"traceutil/trace.go:171","msg":"trace[1701299524] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"470.940313ms","start":"2026-02-05T22:14:34.122745Z","end":"2026-02-05T22:14:34.593685Z","steps":["trace[1701299524] 'read index received' (duration: 461.522373ms)","trace[1701299524] 'applied index is now lower than readState.Index' (duration: 9.41686ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:34.59485Z","caller":"traceutil/trace.go:171","msg":"trace[1991403716] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"710.918705ms","start":"2026-02-05T22:14:33.883919Z","end":"2026-02-05T22:14:34.594837Z","steps":["trace[1991403716] 'process raft request' (duration: 700.388705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:34.595097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.503285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:34.595128Z","caller":"traceutil/trace.go:171","msg":"trace[1691911666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"146.563627ms","start":"2026-02-05T22:14:34.448555Z","end":"2026-02-05T22:14:34.595119Z","steps":["trace[1691911666] 'agreement among raft nodes before linearized reading' (duration: 146.509826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:34.595339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.600728ms","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-05T22:14:34.595361Z","caller":"traceutil/trace.go:171","msg":"trace[306410047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:644; }","duration":"472.659059ms","start":"2026-02-05T22:14:34.122696Z","end":"2026-02-05T22:14:34.595355Z","steps":["trace[306410047] 'agreement among raft nodes before linearized reading' (duration: 472.570957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:34.595381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:34.122677Z","time spent":"472.698951ms","remote":"127.0.0.1:44670","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-05T22:14:34.596528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:33.883906Z","time spent":"710.972686ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:34.861563Z","caller":"traceutil/trace.go:171","msg":"trace[662858432] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"254.99727ms","start":"2026-02-05T22:14:34.605899Z","end":"2026-02-05T22:14:34.860897Z","steps":["trace[662858432] 'process raft request' (duration: 249.603557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:35.268106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.885418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:14:35.268162Z","caller":"traceutil/trace.go:171","msg":"trace[1100408290] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:645; }","duration":"122.97669ms","start":"2026-02-05T22:14:35.145172Z","end":"2026-02-05T22:14:35.268149Z","steps":["trace[1100408290] 'count revisions from in-memory index tree' (duration: 122.828887ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:35.813001Z","caller":"traceutil/trace.go:171","msg":"trace[1749228246] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"122.077506ms","start":"2026-02-05T22:14:35.690897Z","end":"2026-02-05T22:14:35.812975Z","steps":["trace[1749228246] 'read index received' (duration: 121.937302ms)","trace[1749228246] 'applied index is now lower than readState.Index' (duration: 139.524µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:35.81309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.172108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:35.81311Z","caller":"traceutil/trace.go:171","msg":"trace[2016378475] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:646; }","duration":"122.23305ms","start":"2026-02-05T22:14:35.690871Z","end":"2026-02-05T22:14:35.813104Z","steps":["trace[2016378475] 'agreement among raft nodes before linearized reading' (duration: 122.177729ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:35.813184Z","caller":"traceutil/trace.go:171","msg":"trace[949886010] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"410.019993ms","start":"2026-02-05T22:14:35.403141Z","end":"2026-02-05T22:14:35.813161Z","steps":["trace[949886010] 'process raft request' (duration: 409.705084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:35.813275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:35.403124Z","time spent":"410.101254ms","remote":"127.0.0.1:44670","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-05T22:14:44.251776Z","caller":"traceutil/trace.go:171","msg":"trace[879519795] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"161.278396ms","start":"2026-02-05T22:14:44.090482Z","end":"2026-02-05T22:14:44.25176Z","steps":["trace[879519795] 'process raft request' (duration: 108.892336ms)","trace[879519795] 'compare' (duration: 52.320108ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:45.05947Z","caller":"traceutil/trace.go:171","msg":"trace[145596765] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"126.002134ms","start":"2026-02-05T22:14:44.93345Z","end":"2026-02-05T22:14:45.059452Z","steps":["trace[145596765] 'process raft request' (duration: 125.85562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.475144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923328787,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:14:46.724335Z","caller":"traceutil/trace.go:171","msg":"trace[1029064254] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"840.416478ms","start":"2026-02-05T22:14:45.883899Z","end":"2026-02-05T22:14:46.724315Z","steps":["trace[1029064254] 'process raft request' (duration: 840.316705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.724448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:45.88389Z","time spent":"840.492179ms","remote":"127.0.0.1:44670","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-05T22:14:46.731098Z","caller":"traceutil/trace.go:171","msg":"trace[1463317598] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"793.243595ms","start":"2026-02-05T22:14:45.937836Z","end":"2026-02-05T22:14:46.73108Z","steps":["trace[1463317598] 'process raft request' (duration: 792.954898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.731213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:45.937808Z","time spent":"793.344308ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:46.731335Z","caller":"traceutil/trace.go:171","msg":"trace[1379159022] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"756.744791ms","start":"2026-02-05T22:14:45.974582Z","end":"2026-02-05T22:14:46.731327Z","steps":["trace[1379159022] 'read index received' (duration: 750.184628ms)","trace[1379159022] 'applied index is now lower than readState.Index' (duration: 6.559393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:46.731487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.888164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T22:14:46.731508Z","caller":"traceutil/trace.go:171","msg":"trace[1966986076] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:715; }","duration":"756.951166ms","start":"2026-02-05T22:14:45.974551Z","end":"2026-02-05T22:14:46.731502Z","steps":["trace[1966986076] 'agreement among raft nodes before linearized reading' (duration: 756.837643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.731527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:45.974535Z","time spent":"756.987768ms","remote":"127.0.0.1:44398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.140\" "} {"level":"warn","ts":"2026-02-05T22:14:46.731668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.781638ms","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-05T22:14:46.731695Z","caller":"traceutil/trace.go:171","msg":"trace[47584494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"581.832309ms","start":"2026-02-05T22:14:46.149856Z","end":"2026-02-05T22:14:46.731688Z","steps":["trace[47584494] 'agreement among raft nodes before linearized reading' (duration: 581.769747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.731712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:46.149841Z","time spent":"581.867011ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:14:46.731842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.684884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:46.731865Z","caller":"traceutil/trace.go:171","msg":"trace[1774031125] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:715; }","duration":"198.726364ms","start":"2026-02-05T22:14:46.53313Z","end":"2026-02-05T22:14:46.731856Z","steps":["trace[1774031125] 'agreement among raft nodes before linearized reading' (duration: 198.691984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.731957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.06861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:46.731991Z","caller":"traceutil/trace.go:171","msg":"trace[1289471507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"283.123741ms","start":"2026-02-05T22:14:46.448857Z","end":"2026-02-05T22:14:46.731981Z","steps":["trace[1289471507] 'agreement among raft nodes before linearized reading' (duration: 283.076869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.732115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.716371ms","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-05T22:14:46.732135Z","caller":"traceutil/trace.go:171","msg":"trace[485556091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"476.758872ms","start":"2026-02-05T22:14:46.255368Z","end":"2026-02-05T22:14:46.732127Z","steps":["trace[485556091] 'agreement among raft nodes before linearized reading' (duration: 476.707821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:46.732151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:46.255353Z","time spent":"476.794254ms","remote":"127.0.0.1:44670","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-05T22:14:47.280933Z","caller":"traceutil/trace.go:171","msg":"trace[124880695] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"212.120125ms","start":"2026-02-05T22:14:47.068794Z","end":"2026-02-05T22:14:47.280914Z","steps":["trace[124880695] 'process raft request' (duration: 212.010342ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:55.30229Z","caller":"traceutil/trace.go:171","msg":"trace[480042466] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"455.831594ms","start":"2026-02-05T22:14:54.846433Z","end":"2026-02-05T22:14:55.302264Z","steps":["trace[480042466] 'process raft request' (duration: 400.445346ms)","trace[480042466] 'compare' (duration: 54.999848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:55.302429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:54.846416Z","time spent":"455.934317ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:55.302983Z","caller":"traceutil/trace.go:171","msg":"trace[1382487746] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"454.886871ms","start":"2026-02-05T22:14:54.848086Z","end":"2026-02-05T22:14:55.302972Z","steps":["trace[1382487746] 'process raft request' (duration: 454.853579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:55.303037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:54.848072Z","time spent":"454.936332ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:55.303237Z","caller":"traceutil/trace.go:171","msg":"trace[1554033260] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"456.638655ms","start":"2026-02-05T22:14:54.846589Z","end":"2026-02-05T22:14:55.303228Z","steps":["trace[1554033260] 'process raft request' (duration: 456.301966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:55.303285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:54.846581Z","time spent":"456.677367ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:55.952452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.53702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:55.952535Z","caller":"traceutil/trace.go:171","msg":"trace[537930610] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"502.535026ms","start":"2026-02-05T22:14:55.449986Z","end":"2026-02-05T22:14:55.952521Z","steps":["trace[537930610] 'read index received' (duration: 285.838432ms)","trace[537930610] 'applied index is now lower than readState.Index' (duration: 216.695414ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:55.952638Z","caller":"traceutil/trace.go:171","msg":"trace[1078005076] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"600.488396ms","start":"2026-02-05T22:14:55.352107Z","end":"2026-02-05T22:14:55.952595Z","steps":["trace[1078005076] 'process raft request' (duration: 383.74507ms)","trace[1078005076] 'compare' (duration: 216.408257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:55.952683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:55.352086Z","time spent":"600.573219ms","remote":"127.0.0.1:44670","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-05T22:14:55.952696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.253892ms","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-05T22:14:55.952758Z","caller":"traceutil/trace.go:171","msg":"trace[292939945] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:746; }","duration":"268.362805ms","start":"2026-02-05T22:14:55.684379Z","end":"2026-02-05T22:14:55.952742Z","steps":["trace[292939945] 'agreement among raft nodes before linearized reading' (duration: 268.255742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:55.95296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.950754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:14:55.952998Z","caller":"traceutil/trace.go:171","msg":"trace[1350029102] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:746; }","duration":"118.023456ms","start":"2026-02-05T22:14:55.834966Z","end":"2026-02-05T22:14:55.95299Z","steps":["trace[1350029102] 'agreement among raft nodes before linearized reading' (duration: 117.960804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:55.953111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.132712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:55.953133Z","caller":"traceutil/trace.go:171","msg":"trace[340376938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"503.189364ms","start":"2026-02-05T22:14:55.449935Z","end":"2026-02-05T22:14:55.953125Z","steps":["trace[340376938] 'agreement among raft nodes before linearized reading' (duration: 503.156953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:55.953156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:55.449915Z","time spent":"503.236015ms","remote":"127.0.0.1:44348","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-05T22:15:04.263269Z","caller":"traceutil/trace.go:171","msg":"trace[1039231530] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"271.122567ms","start":"2026-02-05T22:15:03.99213Z","end":"2026-02-05T22:15:04.263253Z","steps":["trace[1039231530] 'process raft request' (duration: 271.029554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:04.54899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.537303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:04.549092Z","caller":"traceutil/trace.go:171","msg":"trace[1374634814] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:769; }","duration":"127.719088ms","start":"2026-02-05T22:15:04.421352Z","end":"2026-02-05T22:15:04.549071Z","steps":["trace[1374634814] 'count revisions from in-memory index tree' (duration: 127.44708ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:05.056296Z","caller":"traceutil/trace.go:171","msg":"trace[1609245339] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"160.553417ms","start":"2026-02-05T22:15:04.895706Z","end":"2026-02-05T22:15:05.05626Z","steps":["trace[1609245339] 'process raft request' (duration: 160.343062ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:09.760577Z","caller":"traceutil/trace.go:171","msg":"trace[923920801] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"311.467001ms","start":"2026-02-05T22:15:09.449077Z","end":"2026-02-05T22:15:09.760544Z","steps":["trace[923920801] 'process raft request' (duration: 311.200974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:09.76079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:09.449057Z","time spent":"311.622665ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:09.761588Z","caller":"traceutil/trace.go:171","msg":"trace[550775832] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"309.228294ms","start":"2026-02-05T22:15:09.452342Z","end":"2026-02-05T22:15:09.76157Z","steps":["trace[550775832] 'process raft request' (duration: 309.199283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:09.761636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:09.452333Z","time spent":"309.280335ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:09.761777Z","caller":"traceutil/trace.go:171","msg":"trace[679062629] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"310.195808ms","start":"2026-02-05T22:15:09.451576Z","end":"2026-02-05T22:15:09.761772Z","steps":["trace[679062629] 'process raft request' (duration: 309.808788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:09.761806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:09.451569Z","time spent":"310.220799ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:11.949425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923329080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:15:12.449864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923329080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:15:12.783261Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.661713455s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T22:15:12.783853Z","caller":"traceutil/trace.go:171","msg":"trace[2001371082] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"1.662374323s","start":"2026-02-05T22:15:11.121451Z","end":"2026-02-05T22:15:12.783825Z","steps":["trace[2001371082] 'process raft request' (duration: 1.662171287s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:12.784069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.121434Z","time spent":"1.662521356s","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:13.449029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000375807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-05T22:15:13.449102Z","caller":"traceutil/trace.go:171","msg":"trace[1940594339] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00050628s","start":"2026-02-05T22:15:11.448575Z","end":"2026-02-05T22:15:13.449081Z","steps":["trace[1940594339] 'agreement among raft nodes before linearized reading' (duration: 2.000423148s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.449135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.448554Z","time spent":"2.000574181s","remote":"127.0.0.1:44348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/05 22:15:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-05T22:15:13.50677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.958523ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T22:15:13.506869Z","caller":"traceutil/trace.go:171","msg":"trace[785072066] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"2.058219308s","start":"2026-02-05T22:15:11.448631Z","end":"2026-02-05T22:15:13.506851Z","steps":["trace[785072066] 'read index received' (duration: 1.335954649s)","trace[785072066] 'applied index is now lower than readState.Index' (duration: 722.263609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:13.507505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73804699s","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-05T22:15:13.507525Z","caller":"traceutil/trace.go:171","msg":"trace[2010838126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"1.738132452s","start":"2026-02-05T22:15:11.769385Z","end":"2026-02-05T22:15:13.507517Z","steps":["trace[2010838126] 'agreement among raft nodes before linearized reading' (duration: 1.737984658s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.50764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.769361Z","time spent":"1.738269506s","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:15:13.507923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148756697s","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-05T22:15:13.507938Z","caller":"traceutil/trace.go:171","msg":"trace[1811763141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.148811108s","start":"2026-02-05T22:15:12.359122Z","end":"2026-02-05T22:15:13.507933Z","steps":["trace[1811763141] 'agreement among raft nodes before linearized reading' (duration: 1.148762067s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.507977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:12.3591Z","time spent":"1.148848239s","remote":"127.0.0.1:44670","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-05T22:15:13.508083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737046294s","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-05T22:15:13.508103Z","caller":"traceutil/trace.go:171","msg":"trace[1785104766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.737069214s","start":"2026-02-05T22:15:11.771025Z","end":"2026-02-05T22:15:13.508094Z","steps":["trace[1785104766] 'agreement among raft nodes before linearized reading' (duration: 1.737016753s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.508116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.771022Z","time spent":"1.737090295s","remote":"127.0.0.1:44670","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-05T22:15:13.508222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737217748s","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-05T22:15:13.508236Z","caller":"traceutil/trace.go:171","msg":"trace[1907955688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"1.737243989s","start":"2026-02-05T22:15:11.770987Z","end":"2026-02-05T22:15:13.508231Z","steps":["trace[1907955688] 'agreement among raft nodes before linearized reading' (duration: 1.737199568s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.50825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.770979Z","time spent":"1.737267259s","remote":"127.0.0.1:44670","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-05T22:15:14.029825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923329084,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:15:14.47677Z","caller":"traceutil/trace.go:171","msg":"trace[1134607226] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"961.979484ms","start":"2026-02-05T22:15:13.51476Z","end":"2026-02-05T22:15:14.476739Z","steps":["trace[1134607226] 'process raft request' (duration: 961.740238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.476989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.51474Z","time spent":"962.125028ms","remote":"127.0.0.1:44670","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-05T22:15:14.48171Z","caller":"traceutil/trace.go:171","msg":"trace[620792699] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:874; }","duration":"955.498655ms","start":"2026-02-05T22:15:13.526195Z","end":"2026-02-05T22:15:14.481694Z","steps":["trace[620792699] 'read index received' (duration: 951.444118ms)","trace[620792699] 'applied index is now lower than readState.Index' (duration: 4.053577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:14.481965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.760172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:14.482195Z","caller":"traceutil/trace.go:171","msg":"trace[1707568803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"956.114991ms","start":"2026-02-05T22:15:13.526062Z","end":"2026-02-05T22:15:14.482177Z","steps":["trace[1707568803] 'agreement among raft nodes before linearized reading' (duration: 955.822294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.482413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.526055Z","time spent":"956.343537ms","remote":"127.0.0.1:44348","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-05T22:15:14.482889Z","caller":"traceutil/trace.go:171","msg":"trace[334382750] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"957.266361ms","start":"2026-02-05T22:15:13.52561Z","end":"2026-02-05T22:15:14.482876Z","steps":["trace[334382750] 'process raft request' (duration: 955.067414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.483982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.525569Z","time spent":"958.322558ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:14.484293Z","caller":"traceutil/trace.go:171","msg":"trace[148812200] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"958.265897ms","start":"2026-02-05T22:15:13.526013Z","end":"2026-02-05T22:15:14.484279Z","steps":["trace[148812200] 'process raft request' (duration: 955.160575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.484577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.526006Z","time spent":"958.517334ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:14.485113Z","caller":"traceutil/trace.go:171","msg":"trace[222490543] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"958.846052ms","start":"2026-02-05T22:15:13.526252Z","end":"2026-02-05T22:15:14.485098Z","steps":["trace[222490543] 'process raft request' (duration: 955.363762ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:14.485295Z","caller":"traceutil/trace.go:171","msg":"trace[399151828] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"958.872033ms","start":"2026-02-05T22:15:13.526387Z","end":"2026-02-05T22:15:14.485259Z","steps":["trace[399151828] 'process raft request' (duration: 955.263719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.485995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.52638Z","time spent":"959.52431ms","remote":"127.0.0.1:44454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:15:14.488646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.526242Z","time spent":"959.192491ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:19.217949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.82939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:19.218022Z","caller":"traceutil/trace.go:171","msg":"trace[1637495566] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"321.036801ms","start":"2026-02-05T22:15:18.896975Z","end":"2026-02-05T22:15:19.218012Z","steps":["trace[1637495566] 'read index received' (duration: 71.051037ms)","trace[1637495566] 'applied index is now lower than readState.Index' (duration: 249.984914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:19.218149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.178895ms","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-05T22:15:19.218169Z","caller":"traceutil/trace.go:171","msg":"trace[64266564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"321.222226ms","start":"2026-02-05T22:15:18.896942Z","end":"2026-02-05T22:15:19.218164Z","steps":["trace[64266564] 'agreement among raft nodes before linearized reading' (duration: 321.107913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.218226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:18.89692Z","time spent":"321.300248ms","remote":"127.0.0.1:44670","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-05T22:15:19.218401Z","caller":"traceutil/trace.go:171","msg":"trace[822328760] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"573.629334ms","start":"2026-02-05T22:15:18.644765Z","end":"2026-02-05T22:15:19.218394Z","steps":["trace[822328760] 'process raft request' (duration: 323.30456ms)","trace[822328760] 'compare' (duration: 249.151013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:19.218451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:18.64475Z","time spent":"573.669996ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:21.215337Z","caller":"traceutil/trace.go:171","msg":"trace[164199543] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"540.320764ms","start":"2026-02-05T22:15:20.675002Z","end":"2026-02-05T22:15:21.215323Z","steps":["trace[164199543] 'process raft request' (duration: 535.5659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:21.215432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:20.674981Z","time spent":"540.394875ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:22.814706Z","caller":"traceutil/trace.go:171","msg":"trace[569415356] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"154.532439ms","start":"2026-02-05T22:15:22.66013Z","end":"2026-02-05T22:15:22.814662Z","steps":["trace[569415356] 'process raft request' (duration: 146.799007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.145135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.351487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:23.145211Z","caller":"traceutil/trace.go:171","msg":"trace[974043087] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"306.293706ms","start":"2026-02-05T22:15:22.838905Z","end":"2026-02-05T22:15:23.145198Z","steps":["trace[974043087] 'process raft request' (duration: 17.824065ms)","trace[974043087] 'compare' (duration: 288.261675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:23.14525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:22.838891Z","time spent":"306.342488ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:23.757777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.52082ms","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-05T22:15:23.757904Z","caller":"traceutil/trace.go:171","msg":"trace[1076035107] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:865; }","duration":"330.666634ms","start":"2026-02-05T22:15:23.427221Z","end":"2026-02-05T22:15:23.757888Z","steps":["trace[1076035107] 'range keys from in-memory index tree' (duration: 330.50336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.759219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.264332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:23.759261Z","caller":"traceutil/trace.go:171","msg":"trace[2056618312] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"480.224632ms","start":"2026-02-05T22:15:23.279029Z","end":"2026-02-05T22:15:23.759254Z","steps":["trace[2056618312] 'read index received' (duration: 344.036193ms)","trace[2056618312] 'applied index is now lower than readState.Index' (duration: 136.187749ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:23.759389Z","caller":"traceutil/trace.go:171","msg":"trace[214391817] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"531.937384ms","start":"2026-02-05T22:15:23.227444Z","end":"2026-02-05T22:15:23.759382Z","steps":["trace[214391817] 'process raft request' (duration: 395.462447ms)","trace[214391817] 'compare' (duration: 135.942613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:23.759426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:23.227436Z","time spent":"531.971436ms","remote":"127.0.0.1:44670","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-05T22:15:23.759578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.547641ms","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-05T22:15:23.759607Z","caller":"traceutil/trace.go:171","msg":"trace[432004048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:866; }","duration":"480.595393ms","start":"2026-02-05T22:15:23.279006Z","end":"2026-02-05T22:15:23.759601Z","steps":["trace[432004048] 'agreement among raft nodes before linearized reading' (duration: 480.515831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.759637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:23.278995Z","time spent":"480.637434ms","remote":"127.0.0.1:44670","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-05T22:15:23.75975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.155693ms","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-05T22:15:23.759769Z","caller":"traceutil/trace.go:171","msg":"trace[121796135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:866; }","duration":"415.176753ms","start":"2026-02-05T22:15:23.344587Z","end":"2026-02-05T22:15:23.759764Z","steps":["trace[121796135] 'agreement among raft nodes before linearized reading' (duration: 415.126242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.759784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:23.344547Z","time spent":"415.233825ms","remote":"127.0.0.1:44670","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-05T22:15:23.760002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.604066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:23.760024Z","caller":"traceutil/trace.go:171","msg":"trace[1736838490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"311.627437ms","start":"2026-02-05T22:15:23.448391Z","end":"2026-02-05T22:15:23.760018Z","steps":["trace[1736838490] 'agreement among raft nodes before linearized reading' (duration: 311.591676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.760041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:23.448353Z","time spent":"311.684468ms","remote":"127.0.0.1:44348","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-05T22:15:24.151566Z","caller":"traceutil/trace.go:171","msg":"trace[1187022278] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"385.508636ms","start":"2026-02-05T22:15:23.76603Z","end":"2026-02-05T22:15:24.151538Z","steps":["trace[1187022278] 'process raft request' (duration: 302.777844ms)","trace[1187022278] 'compare' (duration: 82.150877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:24.151695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:23.766024Z","time spent":"385.602779ms","remote":"127.0.0.1:44670","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-05T22:15:24.749818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.105729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:15:24.749906Z","caller":"traceutil/trace.go:171","msg":"trace[64473293] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:868; }","duration":"159.236212ms","start":"2026-02-05T22:15:24.590653Z","end":"2026-02-05T22:15:24.749889Z","steps":["trace[64473293] 'count revisions from in-memory index tree' (duration: 158.992736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:24.750328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.468893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:15:24.750353Z","caller":"traceutil/trace.go:171","msg":"trace[1348539581] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:868; }","duration":"321.522494ms","start":"2026-02-05T22:15:24.428823Z","end":"2026-02-05T22:15:24.750345Z","steps":["trace[1348539581] 'count revisions from in-memory index tree' (duration: 321.39446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:24.750373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:24.428788Z","time spent":"321.577665ms","remote":"127.0.0.1:34696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:15:24.750511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.60476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:15:24.750603Z","caller":"traceutil/trace.go:171","msg":"trace[1393426682] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:868; }","duration":"162.787735ms","start":"2026-02-05T22:15:24.587796Z","end":"2026-02-05T22:15:24.750584Z","steps":["trace[1393426682] 'count revisions from in-memory index tree' (duration: 162.459646ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:26.177208Z","caller":"traceutil/trace.go:171","msg":"trace[1447021574] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"300.225568ms","start":"2026-02-05T22:15:25.876936Z","end":"2026-02-05T22:15:26.177162Z","steps":["trace[1447021574] 'read index received' (duration: 299.9608ms)","trace[1447021574] 'applied index is now lower than readState.Index' (duration: 263.508µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:26.17743Z","caller":"traceutil/trace.go:171","msg":"trace[1066054509] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"396.114908ms","start":"2026-02-05T22:15:25.78126Z","end":"2026-02-05T22:15:26.177375Z","steps":["trace[1066054509] 'process raft request' (duration: 395.719187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:26.177575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.582957ms","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-05T22:15:26.177633Z","caller":"traceutil/trace.go:171","msg":"trace[1474775386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"300.71728ms","start":"2026-02-05T22:15:25.876885Z","end":"2026-02-05T22:15:26.177603Z","steps":["trace[1474775386] 'agreement among raft nodes before linearized reading' (duration: 300.477574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:26.177633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:25.781233Z","time spent":"396.284172ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:26.177678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:25.876855Z","time spent":"300.810933ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:15:26.400788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.40819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T22:15:26.400867Z","caller":"traceutil/trace.go:171","msg":"trace[396428155] range","detail":"{range_begin:/registry/masterleases/162.253.55.140; range_end:; response_count:1; response_revision:871; }","duration":"422.552524ms","start":"2026-02-05T22:15:25.978294Z","end":"2026-02-05T22:15:26.400847Z","steps":["trace[396428155] 'agreement among raft nodes before linearized reading' (duration: 199.806732ms)","trace[396428155] 'range keys from in-memory index tree' (duration: 222.554698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:26.400898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:25.978264Z","time spent":"422.628616ms","remote":"127.0.0.1:44398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.140\" "} {"level":"warn","ts":"2026-02-05T22:15:26.401078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.56391ms","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-05T22:15:26.401096Z","caller":"traceutil/trace.go:171","msg":"trace[866424083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:871; }","duration":"242.627891ms","start":"2026-02-05T22:15:26.158459Z","end":"2026-02-05T22:15:26.401087Z","steps":["trace[866424083] 'agreement among raft nodes before linearized reading' (duration: 19.658223ms)","trace[866424083] 'range keys from in-memory index tree' (duration: 222.930946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:26.401171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.390962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:26.401188Z","caller":"traceutil/trace.go:171","msg":"trace[1870821100] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:871; }","duration":"165.435734ms","start":"2026-02-05T22:15:26.235746Z","end":"2026-02-05T22:15:26.401182Z","steps":["trace[1870821100] 'count revisions from in-memory index tree' (duration: 165.33683ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:26.401466Z","caller":"traceutil/trace.go:171","msg":"trace[568920942] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"163.958275ms","start":"2026-02-05T22:15:26.237499Z","end":"2026-02-05T22:15:26.401458Z","steps":["trace[568920942] 'process raft request' (duration: 131.533967ms)","trace[568920942] 'compare' (duration: 31.285368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:26.837004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.030616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:26.837075Z","caller":"traceutil/trace.go:171","msg":"trace[1021292762] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"341.66307ms","start":"2026-02-05T22:15:26.495401Z","end":"2026-02-05T22:15:26.837064Z","steps":["trace[1021292762] 'read index received' (duration: 42.426049ms)","trace[1021292762] 'applied index is now lower than readState.Index' (duration: 299.236241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:26.837153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.748952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:26.837115Z","caller":"traceutil/trace.go:171","msg":"trace[1471588342] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"365.140943ms","start":"2026-02-05T22:15:26.471935Z","end":"2026-02-05T22:15:26.837076Z","steps":["trace[1471588342] 'process raft request' (duration: 65.983914ms)","trace[1471588342] 'compare' (duration: 298.962584ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:26.837167Z","caller":"traceutil/trace.go:171","msg":"trace[665943661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"341.784454ms","start":"2026-02-05T22:15:26.495377Z","end":"2026-02-05T22:15:26.837162Z","steps":["trace[665943661] 'agreement among raft nodes before linearized reading' (duration: 341.733712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:26.837189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:26.495366Z","time spent":"341.819444ms","remote":"127.0.0.1:44376","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-05T22:15:26.837285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:26.47191Z","time spent":"365.274107ms","remote":"127.0.0.1:44398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:27.002307Z","caller":"traceutil/trace.go:171","msg":"trace[2051533132] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"156.435309ms","start":"2026-02-05T22:15:26.845844Z","end":"2026-02-05T22:15:27.002279Z","steps":["trace[2051533132] 'read index received' (duration: 111.114394ms)","trace[2051533132] 'applied index is now lower than readState.Index' (duration: 45.319585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:27.00245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.602854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-05T22:15:27.00247Z","caller":"traceutil/trace.go:171","msg":"trace[2006041281] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:875; }","duration":"156.670326ms","start":"2026-02-05T22:15:26.845794Z","end":"2026-02-05T22:15:27.002465Z","steps":["trace[2006041281] 'agreement among raft nodes before linearized reading' (duration: 156.573373ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:27.00268Z","caller":"traceutil/trace.go:171","msg":"trace[2147259320] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"157.595949ms","start":"2026-02-05T22:15:26.845077Z","end":"2026-02-05T22:15:27.002673Z","steps":["trace[2147259320] 'process raft request' (duration: 111.912775ms)","trace[2147259320] 'compare' (duration: 44.899234ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:27.881123Z","caller":"traceutil/trace.go:171","msg":"trace[1176772865] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"277.665729ms","start":"2026-02-05T22:15:27.603445Z","end":"2026-02-05T22:15:27.881111Z","steps":["trace[1176772865] 'process raft request' (duration: 215.77594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.902383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.921634ms","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-05T22:15:28.902488Z","caller":"traceutil/trace.go:171","msg":"trace[459133548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"483.029196ms","start":"2026-02-05T22:15:28.419414Z","end":"2026-02-05T22:15:28.902444Z","steps":["trace[459133548] 'range keys from in-memory index tree' (duration: 482.78528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.902536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.419409Z","time spent":"483.117608ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:15:28.902584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.868761ms","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-05T22:15:28.902649Z","caller":"traceutil/trace.go:171","msg":"trace[456009963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"395.972624ms","start":"2026-02-05T22:15:28.506659Z","end":"2026-02-05T22:15:28.902632Z","steps":["trace[456009963] 'range keys from in-memory index tree' (duration: 395.716458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.902686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.506643Z","time spent":"396.035035ms","remote":"127.0.0.1:44670","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-05T22:15:28.902789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.985178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:28.902834Z","caller":"traceutil/trace.go:171","msg":"trace[143909024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"395.0166ms","start":"2026-02-05T22:15:28.507788Z","end":"2026-02-05T22:15:28.902805Z","steps":["trace[143909024] 'range keys from in-memory index tree' (duration: 394.961908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.902864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.507784Z","time spent":"395.074951ms","remote":"127.0.0.1:44376","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-05T22:15:28.90314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.118558ms","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-05T22:15:28.903175Z","caller":"traceutil/trace.go:171","msg":"trace[1021998774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"662.21342ms","start":"2026-02-05T22:15:28.24095Z","end":"2026-02-05T22:15:28.903164Z","steps":["trace[1021998774] 'range keys from in-memory index tree' (duration: 661.994375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.903207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.240918Z","time spent":"662.282512ms","remote":"127.0.0.1:44670","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-05T22:15:28.903432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.169274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:28.903464Z","caller":"traceutil/trace.go:171","msg":"trace[1677369280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"619.248516ms","start":"2026-02-05T22:15:28.284204Z","end":"2026-02-05T22:15:28.903452Z","steps":["trace[1677369280] 'range keys from in-memory index tree' (duration: 619.079322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.90349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.284184Z","time spent":"619.300758ms","remote":"127.0.0.1:44348","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-05T22:15:28.903523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.916046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:28.903559Z","caller":"traceutil/trace.go:171","msg":"trace[2085787396] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:877; }","duration":"484.986198ms","start":"2026-02-05T22:15:28.418563Z","end":"2026-02-05T22:15:28.903549Z","steps":["trace[2085787396] 'count revisions from in-memory index tree' (duration: 484.851714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:28.903588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.418519Z","time spent":"485.060599ms","remote":"127.0.0.1:44538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-05T22:15:29.330958Z","caller":"traceutil/trace.go:171","msg":"trace[1772352478] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"383.307239ms","start":"2026-02-05T22:15:28.947629Z","end":"2026-02-05T22:15:29.330936Z","steps":["trace[1772352478] 'process raft request' (duration: 382.887598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:29.331798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.947616Z","time spent":"384.007737ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:29.340827Z","caller":"traceutil/trace.go:171","msg":"trace[1155236016] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"392.392537ms","start":"2026-02-05T22:15:28.948413Z","end":"2026-02-05T22:15:29.340805Z","steps":["trace[1155236016] 'process raft request' (duration: 392.181361ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:29.34093Z","caller":"traceutil/trace.go:171","msg":"trace[441294507] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:914; }","duration":"332.011179ms","start":"2026-02-05T22:15:29.008908Z","end":"2026-02-05T22:15:29.340919Z","steps":["trace[441294507] 'read index received' (duration: 321.658888ms)","trace[441294507] 'applied index is now lower than readState.Index' (duration: 10.351651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:29.340958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.948402Z","time spent":"392.485939ms","remote":"127.0.0.1:44670","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-05T22:15:29.341354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.44494ms","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-05T22:15:29.341373Z","caller":"traceutil/trace.go:171","msg":"trace[1487274343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"332.488141ms","start":"2026-02-05T22:15:29.008879Z","end":"2026-02-05T22:15:29.341367Z","steps":["trace[1487274343] 'agreement among raft nodes before linearized reading' (duration: 332.405139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:29.341387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:29.008865Z","time spent":"332.518982ms","remote":"127.0.0.1:44670","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":"info","ts":"2026-02-05T22:15:29.341464Z","caller":"traceutil/trace.go:171","msg":"trace[1011330134] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"387.312574ms","start":"2026-02-05T22:15:28.954146Z","end":"2026-02-05T22:15:29.341459Z","steps":["trace[1011330134] 'process raft request' (duration: 386.613456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:29.341491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:28.954129Z","time spent":"387.345794ms","remote":"127.0.0.1:44670","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-05T22:15:35.74975Z","caller":"traceutil/trace.go:171","msg":"trace[103385541] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"339.545956ms","start":"2026-02-05T22:15:35.410179Z","end":"2026-02-05T22:15:35.749725Z","steps":["trace[103385541] 'process raft request' (duration: 339.3287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:35.749903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:35.41017Z","time spent":"339.647759ms","remote":"127.0.0.1:44670","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-05T22:15:36.009284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.845802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:36.009504Z","caller":"traceutil/trace.go:171","msg":"trace[374376334] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"148.531152ms","start":"2026-02-05T22:15:35.860957Z","end":"2026-02-05T22:15:36.009488Z","steps":["trace[374376334] 'process raft request' (duration: 148.489761ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:36.00982Z","caller":"traceutil/trace.go:171","msg":"trace[1733244539] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"598.918084ms","start":"2026-02-05T22:15:35.41089Z","end":"2026-02-05T22:15:36.009808Z","steps":["trace[1733244539] 'process raft request' (duration: 483.463286ms)","trace[1733244539] 'compare' (duration: 114.680598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:36.009888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:35.410884Z","time spent":"598.970696ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:36.010026Z","caller":"traceutil/trace.go:171","msg":"trace[1341186380] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"599.091019ms","start":"2026-02-05T22:15:35.410926Z","end":"2026-02-05T22:15:36.010017Z","steps":["trace[1341186380] 'process raft request' (duration: 598.455082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:36.010079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:35.410922Z","time spent":"599.13203ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:36.010231Z","caller":"traceutil/trace.go:171","msg":"trace[1231970376] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:932; }","duration":"564.988708ms","start":"2026-02-05T22:15:35.445233Z","end":"2026-02-05T22:15:36.010222Z","steps":["trace[1231970376] 'read index received' (duration: 305.177626ms)","trace[1231970376] 'applied index is now lower than readState.Index' (duration: 259.810152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:36.010459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.219045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:36.010496Z","caller":"traceutil/trace.go:171","msg":"trace[1744607680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"565.265235ms","start":"2026-02-05T22:15:35.445222Z","end":"2026-02-05T22:15:36.010487Z","steps":["trace[1744607680] 'agreement among raft nodes before linearized reading' (duration: 565.209834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:36.010523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:35.445216Z","time spent":"565.301726ms","remote":"127.0.0.1:44348","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-05T22:15:36.010721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.770454ms","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-05T22:15:36.010757Z","caller":"traceutil/trace.go:171","msg":"trace[1647741849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"561.824065ms","start":"2026-02-05T22:15:35.448925Z","end":"2026-02-05T22:15:36.010749Z","steps":["trace[1647741849] 'agreement among raft nodes before linearized reading' (duration: 561.720933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:36.010783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:35.448921Z","time spent":"561.856137ms","remote":"127.0.0.1:44670","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-05T22:15:36.011137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.902177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:36.011176Z","caller":"traceutil/trace.go:171","msg":"trace[2096986534] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:897; }","duration":"191.975799ms","start":"2026-02-05T22:15:35.819191Z","end":"2026-02-05T22:15:36.011167Z","steps":["trace[2096986534] 'agreement among raft nodes before linearized reading' (duration: 191.918268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:36.011336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.359741ms","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-05T22:15:36.011369Z","caller":"traceutil/trace.go:171","msg":"trace[1019062344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"559.400642ms","start":"2026-02-05T22:15:35.451961Z","end":"2026-02-05T22:15:36.011361Z","steps":["trace[1019062344] 'agreement among raft nodes before linearized reading' (duration: 559.3259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:36.011398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:35.451956Z","time spent":"559.435853ms","remote":"127.0.0.1:44670","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-05T22:15:52.393594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.703259ms","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-05T22:15:52.393864Z","caller":"traceutil/trace.go:171","msg":"trace[934110049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"235.001386ms","start":"2026-02-05T22:15:52.158845Z","end":"2026-02-05T22:15:52.393846Z","steps":["trace[934110049] 'range keys from in-memory index tree' (duration: 234.537204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:52.394075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.587124ms","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-05T22:15:52.394131Z","caller":"traceutil/trace.go:171","msg":"trace[1184909161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"257.687816ms","start":"2026-02-05T22:15:52.136432Z","end":"2026-02-05T22:15:52.394119Z","steps":["trace[1184909161] 'range keys from in-memory index tree' (duration: 257.424671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:52.394517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.156544ms","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-05T22:15:52.394619Z","caller":"traceutil/trace.go:171","msg":"trace[1117032645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"240.278577ms","start":"2026-02-05T22:15:52.154329Z","end":"2026-02-05T22:15:52.394608Z","steps":["trace[1117032645] 'range keys from in-memory index tree' (duration: 240.037841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:52.394897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.668079ms","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-05T22:15:52.395009Z","caller":"traceutil/trace.go:171","msg":"trace[1881697941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"308.761692ms","start":"2026-02-05T22:15:52.086193Z","end":"2026-02-05T22:15:52.394955Z","steps":["trace[1881697941] 'range keys from in-memory index tree' (duration: 308.577948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:52.395094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.086175Z","time spent":"308.875464ms","remote":"127.0.0.1:44670","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-05T22:15:52.950139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388175923329467,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:15:53.119796Z","caller":"traceutil/trace.go:171","msg":"trace[460770984] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"720.442835ms","start":"2026-02-05T22:15:52.399335Z","end":"2026-02-05T22:15:53.119778Z","steps":["trace[460770984] 'process raft request' (duration: 720.348433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:53.119914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.39932Z","time spent":"720.527677ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:53.267918Z","caller":"traceutil/trace.go:171","msg":"trace[1576718482] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1053; }","duration":"818.523724ms","start":"2026-02-05T22:15:52.449373Z","end":"2026-02-05T22:15:53.267896Z","steps":["trace[1576718482] 'read index received' (duration: 670.457107ms)","trace[1576718482] 'applied index is now lower than readState.Index' (duration: 148.065347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:53.268042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.655227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:53.268075Z","caller":"traceutil/trace.go:171","msg":"trace[1412174023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"818.742499ms","start":"2026-02-05T22:15:52.449321Z","end":"2026-02-05T22:15:53.268064Z","steps":["trace[1412174023] 'agreement among raft nodes before linearized reading' (duration: 818.654627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:53.268107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.449303Z","time spent":"818.797031ms","remote":"127.0.0.1:44348","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-05T22:15:53.268373Z","caller":"traceutil/trace.go:171","msg":"trace[1098749712] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"868.897443ms","start":"2026-02-05T22:15:52.399464Z","end":"2026-02-05T22:15:53.268362Z","steps":["trace[1098749712] 'process raft request' (duration: 867.852017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:53.268448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.399459Z","time spent":"868.939414ms","remote":"127.0.0.1:44670","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-05T22:15:53.268601Z","caller":"traceutil/trace.go:171","msg":"trace[2137295585] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"863.66187ms","start":"2026-02-05T22:15:52.404926Z","end":"2026-02-05T22:15:53.268588Z","steps":["trace[2137295585] 'process raft request' (duration: 862.857791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:53.268788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.404908Z","time spent":"863.835645ms","remote":"127.0.0.1:44670","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-05T22:15:53.268969Z","caller":"traceutil/trace.go:171","msg":"trace[1938633121] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"863.956448ms","start":"2026-02-05T22:15:52.404998Z","end":"2026-02-05T22:15:53.268954Z","steps":["trace[1938633121] 'process raft request' (duration: 862.82924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:53.269027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.404985Z","time spent":"864.01167ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:53.269173Z","caller":"traceutil/trace.go:171","msg":"trace[1926702301] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"864.107053ms","start":"2026-02-05T22:15:52.405054Z","end":"2026-02-05T22:15:53.269161Z","steps":["trace[1926702301] 'process raft request' (duration: 862.806879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:53.269226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:52.405047Z","time spent":"864.150264ms","remote":"127.0.0.1:44670","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-05T22:16:54.471631Z","caller":"traceutil/trace.go:171","msg":"trace[219384454] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"143.159107ms","start":"2026-02-05T22:16:54.328445Z","end":"2026-02-05T22:16:54.471604Z","steps":["trace[219384454] 'process raft request' (duration: 142.766648ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:19:18.667963Z","caller":"traceutil/trace.go:171","msg":"trace[986619058] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"428.114977ms","start":"2026-02-05T22:19:18.239827Z","end":"2026-02-05T22:19:18.667942Z","steps":["trace[986619058] 'read index received' (duration: 427.939102ms)","trace[986619058] 'applied index is now lower than readState.Index' (duration: 174.405µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:19:18.668086Z","caller":"traceutil/trace.go:171","msg":"trace[782949061] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"480.532406ms","start":"2026-02-05T22:19:18.187545Z","end":"2026-02-05T22:19:18.668077Z","steps":["trace[782949061] 'process raft request' (duration: 480.233879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:19:18.668193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:19:18.187532Z","time spent":"480.568666ms","remote":"127.0.0.1:44670","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-05T22:19:18.668688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.585888ms","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-05T22:19:18.668735Z","caller":"traceutil/trace.go:171","msg":"trace[1582985294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1879; }","duration":"428.920746ms","start":"2026-02-05T22:19:18.239799Z","end":"2026-02-05T22:19:18.668719Z","steps":["trace[1582985294] 'agreement among raft nodes before linearized reading' (duration: 428.519987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:19:18.668768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:19:18.239787Z","time spent":"428.973467ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:19:18.668749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.632657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:19:18.668843Z","caller":"traceutil/trace.go:171","msg":"trace[1257651650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"219.77324ms","start":"2026-02-05T22:19:18.449046Z","end":"2026-02-05T22:19:18.668819Z","steps":["trace[1257651650] 'agreement among raft nodes before linearized reading' (duration: 219.634937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:19:18.669196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.577962ms","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-05T22:19:18.669235Z","caller":"traceutil/trace.go:171","msg":"trace[233806423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1879; }","duration":"183.653493ms","start":"2026-02-05T22:19:18.48557Z","end":"2026-02-05T22:19:18.669223Z","steps":["trace[233806423] 'agreement among raft nodes before linearized reading' (duration: 183.54121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:19:18.669427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.280826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T22:19:18.669457Z","caller":"traceutil/trace.go:171","msg":"trace[1335756895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1879; }","duration":"176.335327ms","start":"2026-02-05T22:19:18.493112Z","end":"2026-02-05T22:19:18.669448Z","steps":["trace[1335756895] 'agreement among raft nodes before linearized reading' (duration: 176.254875ms)"],"step_count":1}