{"level":"warn","ts":"2026-01-30T04:05:29.52106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T04:05:29.522156Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-01-30T04:05:29.522375Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T04:05:29.524896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-01-30T04:05:29.52505Z","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-01-30T04:05:29.528709Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-01-30T04:05:29.530169Z","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://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-01-30T04:05:29.537116Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.644178ms"} {"level":"info","ts":"2026-01-30T04:05:29.560568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-01-30T04:05:29.560675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T04:05:29.560696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-01-30T04:05:29.560705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T04:05:29.560713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-01-30T04:05:29.56076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-01-30T04:05:29.573266Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T04:05:29.577891Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T04:05:29.580953Z","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-01-30T04:05:29.585447Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T04:05:29.585628Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T04:05:29.586105Z","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-01-30T04:05:29.586328Z","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-01-30T04:05:29.586613Z","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-01-30T04:05:29.586566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-01-30T04:05:29.586855Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-01-30T04:05:29.619718Z","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-01-30T04:05:29.619928Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-01-30T04:05:29.620023Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-01-30T04:05:29.621534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T04:05:29.621565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T04:05:30.161474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T04:05:30.161522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T04:05:30.161536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-01-30T04:05:30.161547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-01-30T04:05:30.161552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-01-30T04:05:30.161566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-01-30T04:05:30.161583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-01-30T04:05:30.164988Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T04:05:30.165012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T04:05:30.165236Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:05:30.165274Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T04:05:30.166093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T04:05:30.166111Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T04:05:30.167452Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-01-30T04:05:30.171317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T04:05:30.206866Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:05:30.206951Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:05:30.206969Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-30T04:05:32.520435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564730457577589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T04:05:32.663718Z","caller":"traceutil/trace.go:171","msg":"trace[1665907186] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"647.200309ms","start":"2026-01-30T04:05:32.016493Z","end":"2026-01-30T04:05:32.663693Z","steps":["trace[1665907186] 'process raft request' (duration: 647.005755ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:32.664343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.016483Z","time spent":"647.375053ms","remote":"127.0.0.1:47890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:05:33.854621Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.13462985s","expected-duration":"1s"} {"level":"info","ts":"2026-01-30T04:05:33.855727Z","caller":"traceutil/trace.go:171","msg":"trace[1788797980] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.827109667s","start":"2026-01-30T04:05:32.028587Z","end":"2026-01-30T04:05:33.855696Z","steps":["trace[1788797980] 'process raft request' (duration: 1.826544315s)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:05:33.85634Z","caller":"traceutil/trace.go:171","msg":"trace[1068528245] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"1.836602458s","start":"2026-01-30T04:05:32.01972Z","end":"2026-01-30T04:05:33.856323Z","steps":["trace[1068528245] 'read index received' (duration: 644.932688ms)","trace[1068528245] 'applied index is now lower than readState.Index' (duration: 1.19166845s)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:05:33.858202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.028567Z","time spent":"1.827404494s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:05:33.862998Z","caller":"traceutil/trace.go:171","msg":"trace[1278522450] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.832499228s","start":"2026-01-30T04:05:32.030489Z","end":"2026-01-30T04:05:33.862988Z","steps":["trace[1278522450] 'process raft request' (duration: 1.825692706s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.863056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.030485Z","time spent":"1.832544839s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:05:33.863189Z","caller":"traceutil/trace.go:171","msg":"trace[1911504075] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.832646252s","start":"2026-01-30T04:05:32.030536Z","end":"2026-01-30T04:05:33.863182Z","steps":["trace[1911504075] 'process raft request' (duration: 1.825683626s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.863231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.03053Z","time spent":"1.832679922s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:05:33.86336Z","caller":"traceutil/trace.go:171","msg":"trace[1283954524] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.800193528s","start":"2026-01-30T04:05:32.063159Z","end":"2026-01-30T04:05:33.863353Z","steps":["trace[1283954524] 'process raft request' (duration: 1.793090019s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.863397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.063135Z","time spent":"1.80024727s","remote":"127.0.0.1:48072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T04:05:33.863512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837198542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.863614Z","caller":"traceutil/trace.go:171","msg":"trace[772041249] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:0; response_revision:10; }","duration":"1.837316786s","start":"2026-01-30T04:05:32.026289Z","end":"2026-01-30T04:05:33.863605Z","steps":["trace[772041249] 'agreement among raft nodes before linearized reading' (duration: 1.833927541s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.863721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.026282Z","time spent":"1.837430708s","remote":"127.0.0.1:47850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-01-30T04:05:33.863852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792900916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.863881Z","caller":"traceutil/trace.go:171","msg":"trace[100784752] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.792960587s","start":"2026-01-30T04:05:32.070913Z","end":"2026-01-30T04:05:33.863874Z","steps":["trace[100784752] 'agreement among raft nodes before linearized reading' (duration: 1.792915266s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.863903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.070901Z","time spent":"1.792995938s","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-01-30T04:05:33.85829Z","caller":"traceutil/trace.go:171","msg":"trace[1398888955] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.827913376s","start":"2026-01-30T04:05:32.03035Z","end":"2026-01-30T04:05:33.858263Z","steps":["trace[1398888955] 'process raft request' (duration: 1.825792458s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.864014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.030344Z","time spent":"1.833652793s","remote":"127.0.0.1:48296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T04:05:33.863868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83037737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-01-30T04:05:33.864169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.137931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.864193Z","caller":"traceutil/trace.go:171","msg":"trace[90148340] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"938.209193ms","start":"2026-01-30T04:05:32.925977Z","end":"2026-01-30T04:05:33.864186Z","steps":["trace[90148340] 'agreement among raft nodes before linearized reading' (duration: 938.180542ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.864211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.925951Z","time spent":"938.255524ms","remote":"127.0.0.1:48196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-01-30T04:05:33.864175Z","caller":"traceutil/trace.go:171","msg":"trace[527560394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"1.830691507s","start":"2026-01-30T04:05:32.033475Z","end":"2026-01-30T04:05:33.864167Z","steps":["trace[527560394] 'agreement among raft nodes before linearized reading' (duration: 1.83037439s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.864479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.033472Z","time spent":"1.830998804s","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.918283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.864816Z","caller":"traceutil/trace.go:171","msg":"trace[381837887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"410.66031ms","start":"2026-01-30T04:05:33.454149Z","end":"2026-01-30T04:05:33.864809Z","steps":["trace[381837887] 'agreement among raft nodes before linearized reading' (duration: 409.951954ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.864842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:33.454123Z","time spent":"410.712332ms","remote":"127.0.0.1:47824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.119081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.865377Z","caller":"traceutil/trace.go:171","msg":"trace[1267455895] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"974.148224ms","start":"2026-01-30T04:05:32.891215Z","end":"2026-01-30T04:05:33.865363Z","steps":["trace[1267455895] 'agreement among raft nodes before linearized reading' (duration: 973.08673ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.865542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.891166Z","time spent":"974.367249ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-01-30T04:05:33.863552Z","caller":"traceutil/trace.go:171","msg":"trace[640854321] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.793586851s","start":"2026-01-30T04:05:32.069958Z","end":"2026-01-30T04:05:33.863545Z","steps":["trace[640854321] 'process raft request' (duration: 1.786322878s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.866177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.069939Z","time spent":"1.79622071s","remote":"127.0.0.1:47978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2846,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:05:33.858453Z","caller":"traceutil/trace.go:171","msg":"trace[1748765014] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.828376696s","start":"2026-01-30T04:05:32.030069Z","end":"2026-01-30T04:05:33.858445Z","steps":["trace[1748765014] 'process raft request' (duration: 1.826010633s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.866975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.030054Z","time spent":"1.836901815s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T04:05:33.863583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84385763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.867707Z","caller":"traceutil/trace.go:171","msg":"trace[310259076] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"1.847990124s","start":"2026-01-30T04:05:32.019708Z","end":"2026-01-30T04:05:33.867698Z","steps":["trace[310259076] 'agreement among raft nodes before linearized reading' (duration: 1.843856841s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.867989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.019704Z","time spent":"1.84827365s","remote":"127.0.0.1:47882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-01-30T04:05:33.86459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836006816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.870833Z","caller":"traceutil/trace.go:171","msg":"trace[1286539603] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.842253346s","start":"2026-01-30T04:05:32.028568Z","end":"2026-01-30T04:05:33.870821Z","steps":["trace[1286539603] 'agreement among raft nodes before linearized reading' (duration: 1.836006146s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.87089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.028563Z","time spent":"1.842295797s","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.64591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.871042Z","caller":"traceutil/trace.go:171","msg":"trace[1002231918] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"944.557985ms","start":"2026-01-30T04:05:32.926476Z","end":"2026-01-30T04:05:33.871034Z","steps":["trace[1002231918] 'agreement among raft nodes before linearized reading' (duration: 937.65241ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.87107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.926467Z","time spent":"944.594885ms","remote":"127.0.0.1:48172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784773494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.871208Z","caller":"traceutil/trace.go:171","msg":"trace[925925602] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.791627347s","start":"2026-01-30T04:05:32.079574Z","end":"2026-01-30T04:05:33.871201Z","steps":["trace[925925602] 'agreement among raft nodes before linearized reading' (duration: 1.784779494s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.871241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.079568Z","time spent":"1.791666429s","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.831155167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.871374Z","caller":"traceutil/trace.go:171","msg":"trace[320732163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"1.837947429s","start":"2026-01-30T04:05:32.033419Z","end":"2026-01-30T04:05:33.871367Z","steps":["trace[320732163] 'agreement among raft nodes before linearized reading' (duration: 1.831190018s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.8714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.033415Z","time spent":"1.83797771s","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83349368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.871546Z","caller":"traceutil/trace.go:171","msg":"trace[163574845] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.840399274s","start":"2026-01-30T04:05:32.031135Z","end":"2026-01-30T04:05:33.871534Z","steps":["trace[163574845] 'agreement among raft nodes before linearized reading' (duration: 1.833499219s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.871578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.031131Z","time spent":"1.840435174s","remote":"127.0.0.1:47906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-01-30T04:05:33.864666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.834023141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-30T04:05:33.872182Z","caller":"traceutil/trace.go:171","msg":"trace[342523543] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"1.841543059s","start":"2026-01-30T04:05:32.030631Z","end":"2026-01-30T04:05:33.872174Z","steps":["trace[342523543] 'agreement among raft nodes before linearized reading' (duration: 1.834025681s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.872211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.030627Z","time spent":"1.84157592s","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-01-30T04:05:33.858423Z","caller":"traceutil/trace.go:171","msg":"trace[1753420138] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.82944805s","start":"2026-01-30T04:05:32.028936Z","end":"2026-01-30T04:05:33.858384Z","steps":["trace[1753420138] 'process raft request' (duration: 1.82630471s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:05:33.875069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:05:32.028927Z","time spent":"1.846115732s","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:06:01.475068Z","caller":"traceutil/trace.go:171","msg":"trace[588097217] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"450.73088ms","start":"2026-01-30T04:06:01.024312Z","end":"2026-01-30T04:06:01.475043Z","steps":["trace[588097217] 'process raft request' (duration: 450.604788ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:01.475269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:01.024304Z","time spent":"450.892026ms","remote":"127.0.0.1:48088","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-01-30T04:06:05.069832Z","caller":"traceutil/trace.go:171","msg":"trace[561458974] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"413.884232ms","start":"2026-01-30T04:06:04.655931Z","end":"2026-01-30T04:06:05.069815Z","steps":["trace[561458974] 'read index received' (duration: 413.780639ms)","trace[561458974] 'applied index is now lower than readState.Index' (duration: 103.003µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:06:05.069902Z","caller":"traceutil/trace.go:171","msg":"trace[1057171942] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"484.572227ms","start":"2026-01-30T04:06:04.585323Z","end":"2026-01-30T04:06:05.069896Z","steps":["trace[1057171942] 'process raft request' (duration: 484.387102ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:05.06996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:04.585306Z","time spent":"484.609617ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:06:05.070093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.162588ms","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-01-30T04:06:05.070108Z","caller":"traceutil/trace.go:171","msg":"trace[425092958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"414.211249ms","start":"2026-01-30T04:06:04.655892Z","end":"2026-01-30T04:06:05.070104Z","steps":["trace[425092958] 'agreement among raft nodes before linearized reading' (duration: 414.128177ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:05.070122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:04.655876Z","time spent":"414.242829ms","remote":"127.0.0.1:48088","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-01-30T04:06:05.070166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.949824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T04:06:05.070177Z","caller":"traceutil/trace.go:171","msg":"trace[1449357921] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:521; }","duration":"383.997116ms","start":"2026-01-30T04:06:04.686176Z","end":"2026-01-30T04:06:05.070173Z","steps":["trace[1449357921] 'agreement among raft nodes before linearized reading' (duration: 383.978015ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:05.070189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:04.686165Z","time spent":"384.020906ms","remote":"127.0.0.1:47850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-01-30T04:06:05.4335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.35952ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-01-30T04:06:05.433609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:05.071464Z","time spent":"362.140599ms","remote":"127.0.0.1:47850","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-30T04:06:05.437324Z","caller":"traceutil/trace.go:171","msg":"trace[1332693018] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"363.428037ms","start":"2026-01-30T04:06:05.073881Z","end":"2026-01-30T04:06:05.437309Z","steps":["trace[1332693018] 'process raft request' (duration: 363.349475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:05.437402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:05.073866Z","time spent":"363.491938ms","remote":"127.0.0.1:48088","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-01-30T04:06:05.752425Z","caller":"traceutil/trace.go:171","msg":"trace[999982947] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"270.820552ms","start":"2026-01-30T04:06:05.481578Z","end":"2026-01-30T04:06:05.752399Z","steps":["trace[999982947] 'read index received' (duration: 241.857567ms)","trace[999982947] 'applied index is now lower than readState.Index' (duration: 28.961875ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:06:05.752499Z","caller":"traceutil/trace.go:171","msg":"trace[420984342] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"317.664767ms","start":"2026-01-30T04:06:05.434768Z","end":"2026-01-30T04:06:05.752433Z","steps":["trace[420984342] 'process raft request' (duration: 288.728652ms)","trace[420984342] 'compare' (duration: 28.790912ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:06:05.752656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:05.434747Z","time spent":"317.82604ms","remote":"127.0.0.1:47850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:06:05.75267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.075508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:06:05.75271Z","caller":"traceutil/trace.go:171","msg":"trace[2104890631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"271.17051ms","start":"2026-01-30T04:06:05.481525Z","end":"2026-01-30T04:06:05.752696Z","steps":["trace[2104890631] 'agreement among raft nodes before linearized reading' (duration: 271.001277ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:05.752984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.869473ms","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-01-30T04:06:05.753013Z","caller":"traceutil/trace.go:171","msg":"trace[1451612178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"164.936614ms","start":"2026-01-30T04:06:05.588067Z","end":"2026-01-30T04:06:05.753004Z","steps":["trace[1451612178] 'agreement among raft nodes before linearized reading' (duration: 164.885593ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:07.406955Z","caller":"traceutil/trace.go:171","msg":"trace[338348378] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"444.32106ms","start":"2026-01-30T04:06:06.962609Z","end":"2026-01-30T04:06:07.40693Z","steps":["trace[338348378] 'process raft request' (duration: 444.208857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:07.407088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:06.962591Z","time spent":"444.429112ms","remote":"127.0.0.1:48088","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-01-30T04:06:10.505853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.016063ms","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-01-30T04:06:10.505917Z","caller":"traceutil/trace.go:171","msg":"trace[1356986609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"694.129905ms","start":"2026-01-30T04:06:09.811772Z","end":"2026-01-30T04:06:10.505902Z","steps":["trace[1356986609] 'range keys from in-memory index tree' (duration: 693.9138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:10.505949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:09.811753Z","time spent":"694.187256ms","remote":"127.0.0.1:48088","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-01-30T04:06:10.593785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.054626ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T04:06:10.594227Z","caller":"traceutil/trace.go:171","msg":"trace[232896016] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"436.582017ms","start":"2026-01-30T04:06:10.157626Z","end":"2026-01-30T04:06:10.594208Z","steps":["trace[232896016] 'read index received' (duration: 219.080941ms)","trace[232896016] 'applied index is now lower than readState.Index' (duration: 217.499606ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:06:10.59446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.827472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-30T04:06:10.594491Z","caller":"traceutil/trace.go:171","msg":"trace[1202309354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"436.890504ms","start":"2026-01-30T04:06:10.157591Z","end":"2026-01-30T04:06:10.594481Z","steps":["trace[1202309354] 'agreement among raft nodes before linearized reading' (duration: 436.828613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:10.59452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:10.157575Z","time spent":"436.937075ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T04:06:10.594648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.292996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-30T04:06:10.594672Z","caller":"traceutil/trace.go:171","msg":"trace[503091584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"390.341856ms","start":"2026-01-30T04:06:10.204323Z","end":"2026-01-30T04:06:10.594665Z","steps":["trace[503091584] 'agreement among raft nodes before linearized reading' (duration: 390.299965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:10.594696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:10.204275Z","time spent":"390.415638ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T04:06:10.594856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.704504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:06:10.594881Z","caller":"traceutil/trace.go:171","msg":"trace[756250323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"109.862018ms","start":"2026-01-30T04:06:10.485011Z","end":"2026-01-30T04:06:10.594873Z","steps":["trace[756250323] 'agreement among raft nodes before linearized reading' (duration: 109.823527ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:13.159235Z","caller":"traceutil/trace.go:171","msg":"trace[1820155429] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"256.530345ms","start":"2026-01-30T04:06:12.902686Z","end":"2026-01-30T04:06:13.159217Z","steps":["trace[1820155429] 'process raft request' (duration: 256.341911ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:13.162665Z","caller":"traceutil/trace.go:171","msg":"trace[960912941] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"113.102481ms","start":"2026-01-30T04:06:13.049553Z","end":"2026-01-30T04:06:13.162656Z","steps":["trace[960912941] 'process raft request' (duration: 113.04871ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:19.644883Z","caller":"traceutil/trace.go:171","msg":"trace[1956198312] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"149.869929ms","start":"2026-01-30T04:06:19.494958Z","end":"2026-01-30T04:06:19.644828Z","steps":["trace[1956198312] 'process raft request' (duration: 149.765716ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:22.7664Z","caller":"traceutil/trace.go:171","msg":"trace[977417977] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"133.045855ms","start":"2026-01-30T04:06:22.633337Z","end":"2026-01-30T04:06:22.766383Z","steps":["trace[977417977] 'process raft request' (duration: 132.954043ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:22.927345Z","caller":"traceutil/trace.go:171","msg":"trace[2046983355] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"156.518617ms","start":"2026-01-30T04:06:22.770809Z","end":"2026-01-30T04:06:22.927328Z","steps":["trace[2046983355] 'process raft request' (duration: 153.758605ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:22.93791Z","caller":"traceutil/trace.go:171","msg":"trace[523316903] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"165.837575ms","start":"2026-01-30T04:06:22.77206Z","end":"2026-01-30T04:06:22.937898Z","steps":["trace[523316903] 'process raft request' (duration: 165.693632ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:22.938549Z","caller":"traceutil/trace.go:171","msg":"trace[1914963717] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"166.571921ms","start":"2026-01-30T04:06:22.77197Z","end":"2026-01-30T04:06:22.938542Z","steps":["trace[1914963717] 'process raft request' (duration: 165.528298ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:22.938752Z","caller":"traceutil/trace.go:171","msg":"trace[781833039] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"166.719294ms","start":"2026-01-30T04:06:22.772023Z","end":"2026-01-30T04:06:22.938743Z","steps":["trace[781833039] 'process raft request' (duration: 165.696722ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:31.145779Z","caller":"traceutil/trace.go:171","msg":"trace[1325661992] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"171.998732ms","start":"2026-01-30T04:06:30.97376Z","end":"2026-01-30T04:06:31.145759Z","steps":["trace[1325661992] 'read index received' (duration: 163.257707ms)","trace[1325661992] 'applied index is now lower than readState.Index' (duration: 8.739905ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:06:31.145962Z","caller":"traceutil/trace.go:171","msg":"trace[503067462] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"179.54651ms","start":"2026-01-30T04:06:30.966406Z","end":"2026-01-30T04:06:31.145952Z","steps":["trace[503067462] 'process raft request' (duration: 170.656252ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:31.14705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.271379ms","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-01-30T04:06:31.147077Z","caller":"traceutil/trace.go:171","msg":"trace[1985311562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"173.3236ms","start":"2026-01-30T04:06:30.973746Z","end":"2026-01-30T04:06:31.147069Z","steps":["trace[1985311562] 'agreement among raft nodes before linearized reading' (duration: 172.346129ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:31.147096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.398366ms","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-01-30T04:06:31.14715Z","caller":"traceutil/trace.go:171","msg":"trace[1946972800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"166.478258ms","start":"2026-01-30T04:06:30.980655Z","end":"2026-01-30T04:06:31.147133Z","steps":["trace[1946972800] 'agreement among raft nodes before linearized reading' (duration: 166.323494ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:32.244742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.579053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T04:06:32.244808Z","caller":"traceutil/trace.go:171","msg":"trace[1895434773] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"766.010847ms","start":"2026-01-30T04:06:31.478785Z","end":"2026-01-30T04:06:32.244796Z","steps":["trace[1895434773] 'read index received' (duration: 212.351332ms)","trace[1895434773] 'applied index is now lower than readState.Index' (duration: 553.658475ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:06:32.244939Z","caller":"traceutil/trace.go:171","msg":"trace[1841093790] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.08844695s","start":"2026-01-30T04:06:31.156483Z","end":"2026-01-30T04:06:32.24493Z","steps":["trace[1841093790] 'process raft request' (duration: 534.622391ms)","trace[1841093790] 'compare' (duration: 553.311677ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:06:32.244973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:31.156473Z","time spent":"1.0884822s","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:06:32.245115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.329093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:06:32.245136Z","caller":"traceutil/trace.go:171","msg":"trace[1422458138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"766.372174ms","start":"2026-01-30T04:06:31.478755Z","end":"2026-01-30T04:06:32.245127Z","steps":["trace[1422458138] 'agreement among raft nodes before linearized reading' (duration: 766.337943ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:32.245152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:31.478741Z","time spent":"766.408165ms","remote":"127.0.0.1:47832","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-01-30T04:06:32.245292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.211717ms","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-01-30T04:06:32.245309Z","caller":"traceutil/trace.go:171","msg":"trace[629818649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"554.228498ms","start":"2026-01-30T04:06:31.691075Z","end":"2026-01-30T04:06:32.245304Z","steps":["trace[629818649] 'agreement among raft nodes before linearized reading' (duration: 554.148506ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:32.245324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:31.691038Z","time spent":"554.282058ms","remote":"127.0.0.1:48088","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-01-30T04:06:32.893594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.051075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:06:32.893644Z","caller":"traceutil/trace.go:171","msg":"trace[242103675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"414.130436ms","start":"2026-01-30T04:06:32.479501Z","end":"2026-01-30T04:06:32.893631Z","steps":["trace[242103675] 'range keys from in-memory index tree' (duration: 413.966413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:32.893666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:32.479485Z","time spent":"414.175888ms","remote":"127.0.0.1:47824","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-01-30T04:06:33.508293Z","caller":"traceutil/trace.go:171","msg":"trace[2104610096] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"355.48523ms","start":"2026-01-30T04:06:33.152791Z","end":"2026-01-30T04:06:33.508276Z","steps":["trace[2104610096] 'read index received' (duration: 355.382807ms)","trace[2104610096] 'applied index is now lower than readState.Index' (duration: 101.703µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:06:33.508456Z","caller":"traceutil/trace.go:171","msg":"trace[1429464239] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"536.045771ms","start":"2026-01-30T04:06:32.972404Z","end":"2026-01-30T04:06:33.50845Z","steps":["trace[1429464239] 'process raft request' (duration: 535.805796ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:33.508514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:32.97238Z","time spent":"536.091393ms","remote":"127.0.0.1:48088","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-01-30T04:06:33.508659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.868718ms","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-01-30T04:06:33.508684Z","caller":"traceutil/trace.go:171","msg":"trace[80839597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"355.920039ms","start":"2026-01-30T04:06:33.152749Z","end":"2026-01-30T04:06:33.508669Z","steps":["trace[80839597] 'agreement among raft nodes before linearized reading' (duration: 355.858858ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:33.508698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:33.152729Z","time spent":"355.96647ms","remote":"127.0.0.1:48088","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-01-30T04:06:33.508775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.24339ms","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-01-30T04:06:33.508788Z","caller":"traceutil/trace.go:171","msg":"trace[1377849194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"336.275193ms","start":"2026-01-30T04:06:33.172507Z","end":"2026-01-30T04:06:33.508783Z","steps":["trace[1377849194] 'agreement among raft nodes before linearized reading' (duration: 336.241491ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:33.508799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:33.172497Z","time spent":"336.298803ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-30T04:06:49.789981Z","caller":"traceutil/trace.go:171","msg":"trace[363494440] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"196.954965ms","start":"2026-01-30T04:06:49.593004Z","end":"2026-01-30T04:06:49.789959Z","steps":["trace[363494440] 'read index received' (duration: 196.808112ms)","trace[363494440] 'applied index is now lower than readState.Index' (duration: 145.833µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:06:49.790275Z","caller":"traceutil/trace.go:171","msg":"trace[1358476334] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"210.660514ms","start":"2026-01-30T04:06:49.579581Z","end":"2026-01-30T04:06:49.790241Z","steps":["trace[1358476334] 'process raft request' (duration: 210.279147ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:49.790514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.492886ms","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-01-30T04:06:49.790544Z","caller":"traceutil/trace.go:171","msg":"trace[1990946824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"197.544347ms","start":"2026-01-30T04:06:49.592991Z","end":"2026-01-30T04:06:49.790535Z","steps":["trace[1990946824] 'agreement among raft nodes before linearized reading' (duration: 197.432555ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:49.790701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.017467ms","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-01-30T04:06:49.790728Z","caller":"traceutil/trace.go:171","msg":"trace[1950422743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"174.073039ms","start":"2026-01-30T04:06:49.616647Z","end":"2026-01-30T04:06:49.79072Z","steps":["trace[1950422743] 'agreement among raft nodes before linearized reading' (duration: 174.003878ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:50.16394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.140223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T04:06:50.164041Z","caller":"traceutil/trace.go:171","msg":"trace[1870132427] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"366.027871ms","start":"2026-01-30T04:06:49.797977Z","end":"2026-01-30T04:06:50.164005Z","steps":["trace[1870132427] 'process raft request' (duration: 76.765476ms)","trace[1870132427] 'compare' (duration: 289.070191ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:06:50.16408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:49.797961Z","time spent":"366.101432ms","remote":"127.0.0.1:48088","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-01-30T04:06:50.206518Z","caller":"traceutil/trace.go:171","msg":"trace[1800730350] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"408.296582ms","start":"2026-01-30T04:06:49.798209Z","end":"2026-01-30T04:06:50.206506Z","steps":["trace[1800730350] 'process raft request' (duration: 408.219641ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:06:50.206591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:06:49.798158Z","time spent":"408.392484ms","remote":"127.0.0.1:48088","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-01-30T04:06:52.035449Z","caller":"traceutil/trace.go:171","msg":"trace[711144039] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"235.636003ms","start":"2026-01-30T04:06:51.79957Z","end":"2026-01-30T04:06:52.035206Z","steps":["trace[711144039] 'process raft request' (duration: 235.45037ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:06:52.37269Z","caller":"traceutil/trace.go:171","msg":"trace[2075898806] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"144.482194ms","start":"2026-01-30T04:06:52.228101Z","end":"2026-01-30T04:06:52.372584Z","steps":["trace[2075898806] 'process raft request' (duration: 144.196789ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:04.955629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.62651ms","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-01-30T04:07:04.955679Z","caller":"traceutil/trace.go:171","msg":"trace[490185388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"673.707211ms","start":"2026-01-30T04:07:04.28196Z","end":"2026-01-30T04:07:04.955667Z","steps":["trace[490185388] 'range keys from in-memory index tree' (duration: 673.526778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:04.9557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:04.281946Z","time spent":"673.749753ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T04:07:04.955864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.341074ms","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-01-30T04:07:04.955877Z","caller":"traceutil/trace.go:171","msg":"trace[1432808971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:868; }","duration":"513.376204ms","start":"2026-01-30T04:07:04.442497Z","end":"2026-01-30T04:07:04.955873Z","steps":["trace[1432808971] 'range keys from in-memory index tree' (duration: 513.277992ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:04.955889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:04.442484Z","time spent":"513.401815ms","remote":"127.0.0.1:48088","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-01-30T04:07:04.955966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.400898ms","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-01-30T04:07:04.955979Z","caller":"traceutil/trace.go:171","msg":"trace[1649980620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"418.436399ms","start":"2026-01-30T04:07:04.537537Z","end":"2026-01-30T04:07:04.955974Z","steps":["trace[1649980620] 'range keys from in-memory index tree' (duration: 418.345967ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:04.95599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:04.537524Z","time spent":"418.46364ms","remote":"127.0.0.1:48088","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-01-30T04:07:04.956082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.08558ms","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-01-30T04:07:04.956094Z","caller":"traceutil/trace.go:171","msg":"trace[1255924487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"415.107392ms","start":"2026-01-30T04:07:04.540982Z","end":"2026-01-30T04:07:04.95609Z","steps":["trace[1255924487] 'range keys from in-memory index tree' (duration: 415.04761ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:04.956108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:04.540978Z","time spent":"415.127822ms","remote":"127.0.0.1:48088","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-01-30T04:07:04.956167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.707277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T04:07:04.956179Z","caller":"traceutil/trace.go:171","msg":"trace[336706750] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:868; }","duration":"399.756798ms","start":"2026-01-30T04:07:04.556418Z","end":"2026-01-30T04:07:04.956175Z","steps":["trace[336706750] 'count revisions from in-memory index tree' (duration: 399.680086ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:04.95619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:04.556413Z","time spent":"399.774339ms","remote":"127.0.0.1:48208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-30T04:07:04.956308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.243777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T04:07:04.956319Z","caller":"traceutil/trace.go:171","msg":"trace[676205189] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:868; }","duration":"267.281188ms","start":"2026-01-30T04:07:04.689035Z","end":"2026-01-30T04:07:04.956316Z","steps":["trace[676205189] 'range keys from in-memory index tree' (duration: 267.173686ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:05.159635Z","caller":"traceutil/trace.go:171","msg":"trace[1294555393] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"199.374233ms","start":"2026-01-30T04:07:04.960236Z","end":"2026-01-30T04:07:05.159611Z","steps":["trace[1294555393] 'process raft request' (duration: 198.370272ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:05.159858Z","caller":"traceutil/trace.go:171","msg":"trace[184187443] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"198.999346ms","start":"2026-01-30T04:07:04.960839Z","end":"2026-01-30T04:07:05.159838Z","steps":["trace[184187443] 'process raft request' (duration: 198.72855ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:05.160191Z","caller":"traceutil/trace.go:171","msg":"trace[1421223797] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"196.137047ms","start":"2026-01-30T04:07:04.964042Z","end":"2026-01-30T04:07:05.160179Z","steps":["trace[1421223797] 'process raft request' (duration: 196.030665ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:05.16029Z","caller":"traceutil/trace.go:171","msg":"trace[2026407993] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"197.700148ms","start":"2026-01-30T04:07:04.962579Z","end":"2026-01-30T04:07:05.160279Z","steps":["trace[2026407993] 'process raft request' (duration: 197.208789ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:08.269861Z","caller":"traceutil/trace.go:171","msg":"trace[714250555] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"117.780991ms","start":"2026-01-30T04:07:08.152062Z","end":"2026-01-30T04:07:08.269843Z","steps":["trace[714250555] 'process raft request' (duration: 117.666049ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:10.54305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564730457579400,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T04:07:10.977867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.946324ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T04:07:10.977934Z","caller":"traceutil/trace.go:171","msg":"trace[1765093243] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"935.514399ms","start":"2026-01-30T04:07:10.042408Z","end":"2026-01-30T04:07:10.977923Z","steps":["trace[1765093243] 'read index received' (duration: 689.510054ms)","trace[1765093243] 'applied index is now lower than readState.Index' (duration: 246.003265ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:07:10.978244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.208923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-01-30T04:07:10.978215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.787904ms","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-01-30T04:07:10.978264Z","caller":"traceutil/trace.go:171","msg":"trace[629969903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"704.256035ms","start":"2026-01-30T04:07:10.274001Z","end":"2026-01-30T04:07:10.978257Z","steps":["trace[629969903] 'agreement among raft nodes before linearized reading' (duration: 704.163483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:10.978281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:10.273988Z","time spent":"704.289655ms","remote":"127.0.0.1:48088","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":"info","ts":"2026-01-30T04:07:10.978283Z","caller":"traceutil/trace.go:171","msg":"trace[567015236] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:885; }","duration":"935.887706ms","start":"2026-01-30T04:07:10.042378Z","end":"2026-01-30T04:07:10.978266Z","steps":["trace[567015236] 'agreement among raft nodes before linearized reading' (duration: 935.750313ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:10.978323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:10.042365Z","time spent":"935.949548ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-01-30T04:07:10.978383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.102334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:07:10.978397Z","caller":"traceutil/trace.go:171","msg":"trace[773642270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"500.137126ms","start":"2026-01-30T04:07:10.478256Z","end":"2026-01-30T04:07:10.978393Z","steps":["trace[773642270] 'agreement among raft nodes before linearized reading' (duration: 500.115705ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:10.978409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:10.478243Z","time spent":"500.163286ms","remote":"127.0.0.1:47824","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-01-30T04:07:21.149133Z","caller":"traceutil/trace.go:171","msg":"trace[36526595] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"110.782999ms","start":"2026-01-30T04:07:21.038331Z","end":"2026-01-30T04:07:21.149114Z","steps":["trace[36526595] 'process raft request' (duration: 110.669537ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:21.428654Z","caller":"traceutil/trace.go:171","msg":"trace[1434795269] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"219.261278ms","start":"2026-01-30T04:07:21.209368Z","end":"2026-01-30T04:07:21.42863Z","steps":["trace[1434795269] 'process raft request' (duration: 145.034806ms)","trace[1434795269] 'compare' (duration: 73.830794ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:07:21.429098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.930028ms","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-01-30T04:07:21.429129Z","caller":"traceutil/trace.go:171","msg":"trace[659711975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"210.0009ms","start":"2026-01-30T04:07:21.219119Z","end":"2026-01-30T04:07:21.42912Z","steps":["trace[659711975] 'agreement among raft nodes before linearized reading' (duration: 209.878898ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:21.428958Z","caller":"traceutil/trace.go:171","msg":"trace[1684277411] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"209.335125ms","start":"2026-01-30T04:07:21.219149Z","end":"2026-01-30T04:07:21.428484Z","steps":["trace[1684277411] 'read index received' (duration: 135.191135ms)","trace[1684277411] 'applied index is now lower than readState.Index' (duration: 74.14266ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:07:21.429603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.039104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-30T04:07:21.429632Z","caller":"traceutil/trace.go:171","msg":"trace[1033939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"189.099484ms","start":"2026-01-30T04:07:21.240523Z","end":"2026-01-30T04:07:21.429623Z","steps":["trace[1033939] 'agreement among raft nodes before linearized reading' (duration: 189.017673ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:21.429791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.955468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-30T04:07:21.429816Z","caller":"traceutil/trace.go:171","msg":"trace[6878347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"181.996339ms","start":"2026-01-30T04:07:21.247812Z","end":"2026-01-30T04:07:21.429808Z","steps":["trace[6878347] 'agreement among raft nodes before linearized reading' (duration: 181.929118ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:23.258504Z","caller":"traceutil/trace.go:171","msg":"trace[1663443766] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"101.562701ms","start":"2026-01-30T04:07:23.156918Z","end":"2026-01-30T04:07:23.258481Z","steps":["trace[1663443766] 'process raft request' (duration: 101.437049ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:23.278237Z","caller":"traceutil/trace.go:171","msg":"trace[1744265545] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"101.301146ms","start":"2026-01-30T04:07:23.176918Z","end":"2026-01-30T04:07:23.27822Z","steps":["trace[1744265545] 'process raft request' (duration: 101.232864ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:23.612809Z","caller":"traceutil/trace.go:171","msg":"trace[1951379266] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"150.417977ms","start":"2026-01-30T04:07:23.462335Z","end":"2026-01-30T04:07:23.612753Z","steps":["trace[1951379266] 'read index received' (duration: 150.189542ms)","trace[1951379266] 'applied index is now lower than readState.Index' (duration: 227.315µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:07:23.613395Z","caller":"traceutil/trace.go:171","msg":"trace[22291117] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"173.459266ms","start":"2026-01-30T04:07:23.439921Z","end":"2026-01-30T04:07:23.61338Z","steps":["trace[22291117] 'process raft request' (duration: 172.65413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:23.614396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.972019ms","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-01-30T04:07:23.614467Z","caller":"traceutil/trace.go:171","msg":"trace[831148082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"152.122272ms","start":"2026-01-30T04:07:23.462331Z","end":"2026-01-30T04:07:23.614453Z","steps":["trace[831148082] 'agreement among raft nodes before linearized reading' (duration: 151.616651ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:23.614887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.988361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:07:23.61493Z","caller":"traceutil/trace.go:171","msg":"trace[1377324864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"133.050692ms","start":"2026-01-30T04:07:23.481869Z","end":"2026-01-30T04:07:23.61492Z","steps":["trace[1377324864] 'agreement among raft nodes before linearized reading' (duration: 132.983361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:23.615449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.50803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-30T04:07:23.615476Z","caller":"traceutil/trace.go:171","msg":"trace[2007843975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"127.54869ms","start":"2026-01-30T04:07:23.48792Z","end":"2026-01-30T04:07:23.615469Z","steps":["trace[2007843975] 'agreement among raft nodes before linearized reading' (duration: 127.476429ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:23.615555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.929048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-30T04:07:23.615571Z","caller":"traceutil/trace.go:171","msg":"trace[2081488919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"127.955428ms","start":"2026-01-30T04:07:23.48761Z","end":"2026-01-30T04:07:23.615566Z","steps":["trace[2081488919] 'agreement among raft nodes before linearized reading' (duration: 127.911567ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:23.928455Z","caller":"traceutil/trace.go:171","msg":"trace[2049315703] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"306.58449ms","start":"2026-01-30T04:07:23.621844Z","end":"2026-01-30T04:07:23.928428Z","steps":["trace[2049315703] 'process raft request' (duration: 239.789138ms)","trace[2049315703] 'compare' (duration: 66.68462ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:07:23.928613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:23.621828Z","time spent":"306.707282ms","remote":"127.0.0.1:48088","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-01-30T04:07:23.935954Z","caller":"traceutil/trace.go:171","msg":"trace[716387715] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:955; }","duration":"137.789388ms","start":"2026-01-30T04:07:23.798146Z","end":"2026-01-30T04:07:23.935935Z","steps":["trace[716387715] 'read index received' (duration: 63.495894ms)","trace[716387715] 'applied index is now lower than readState.Index' (duration: 74.292484ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:07:23.936124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.968902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T04:07:23.936153Z","caller":"traceutil/trace.go:171","msg":"trace[8845631] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:924; }","duration":"138.055514ms","start":"2026-01-30T04:07:23.798089Z","end":"2026-01-30T04:07:23.936145Z","steps":["trace[8845631] 'agreement among raft nodes before linearized reading' (duration: 137.924222ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:07:23.936452Z","caller":"traceutil/trace.go:171","msg":"trace[1032378990] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"312.876428ms","start":"2026-01-30T04:07:23.623567Z","end":"2026-01-30T04:07:23.936443Z","steps":["trace[1032378990] 'process raft request' (duration: 312.236764ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:23.936515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:23.623557Z","time spent":"312.914359ms","remote":"127.0.0.1:48088","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-01-30T04:07:23.936559Z","caller":"traceutil/trace.go:171","msg":"trace[2121398559] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"312.940969ms","start":"2026-01-30T04:07:23.623608Z","end":"2026-01-30T04:07:23.936549Z","steps":["trace[2121398559] 'process raft request' (duration: 312.299966ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:07:23.936623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:07:23.623604Z","time spent":"312.97624ms","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}