{"level":"warn","ts":"2026-08-04T15:15:16.767818Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:15:16.767918Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-08-04T15:15:16.768065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:15:16.768086Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-08-04T15:15:16.768105Z","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-08-04T15:15:16.769014Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-08-04T15:15:16.769095Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-08-04T15:15:16.774641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.325611ms"} {"level":"info","ts":"2026-08-04T15:15:16.781683Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-08-04T15:15:16.781769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:15:16.781903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:15:16.781932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:15:16.781942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:15:16.782032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-08-04T15:15:16.791472Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:15:16.793272Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:15:16.794866Z","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-08-04T15:15:16.797352Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:15:16.797865Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:15:16.798325Z","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-08-04T15:15:16.798611Z","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-08-04T15:15:16.801838Z","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-08-04T15:15:16.80373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-08-04T15:15:16.803916Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-08-04T15:15:16.806366Z","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-08-04T15:15:16.806541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-08-04T15:15:16.80658Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-08-04T15:15:16.806839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:15:16.806907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:15:16.983206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:15:16.983277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:15:16.983314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-08-04T15:15:16.983331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:15:16.983339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-08-04T15:15:16.983463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:15:16.983487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-08-04T15:15:16.984344Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:16.985462Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:15:16.985589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:15:16.985696Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:15:16.986789Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:16.98703Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:16.987106Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:15:16.988587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:15:16.98925Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:15:16.989292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:15:17.001767Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-08-04T15:15:20.348559Z","caller":"traceutil/trace.go:171","msg":"trace[1839574520] linearizableReadLoop","detail":"{readStateIndex:190; appliedIndex:189; }","duration":"271.246045ms","start":"2026-08-04T15:15:20.07728Z","end":"2026-08-04T15:15:20.348526Z","steps":["trace[1839574520] 'read index received' (duration: 262.082714ms)","trace[1839574520] 'applied index is now lower than readState.Index' (duration: 9.162421ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:20.348679Z","caller":"traceutil/trace.go:171","msg":"trace[633512073] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"272.59755ms","start":"2026-08-04T15:15:20.076051Z","end":"2026-08-04T15:15:20.348649Z","steps":["trace[633512073] 'process raft request' (duration: 263.367718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:20.348878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.56492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-08-04T15:15:20.348965Z","caller":"traceutil/trace.go:171","msg":"trace[1569583062] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:187; }","duration":"271.685344ms","start":"2026-08-04T15:15:20.07726Z","end":"2026-08-04T15:15:20.348945Z","steps":["trace[1569583062] 'agreement among raft nodes before linearized reading' (duration: 271.387508ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:22.199781Z","caller":"traceutil/trace.go:171","msg":"trace[1922976363] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"131.200668ms","start":"2026-08-04T15:15:22.068554Z","end":"2026-08-04T15:15:22.199755Z","steps":["trace[1922976363] 'process raft request' (duration: 131.037625ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:27.574969Z","caller":"traceutil/trace.go:171","msg":"trace[1544382269] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"279.528945ms","start":"2026-08-04T15:15:27.295412Z","end":"2026-08-04T15:15:27.574941Z","steps":["trace[1544382269] 'read index received' (duration: 279.30936ms)","trace[1544382269] 'applied index is now lower than readState.Index' (duration: 218.115µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:27.575081Z","caller":"traceutil/trace.go:171","msg":"trace[278327920] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"323.729226ms","start":"2026-08-04T15:15:27.25132Z","end":"2026-08-04T15:15:27.575049Z","steps":["trace[278327920] 'process raft request' (duration: 323.40913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:27.575333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.796099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-08-04T15:15:27.575424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.68323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-08-04T15:15:27.575542Z","caller":"traceutil/trace.go:171","msg":"trace[249498153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:350; }","duration":"179.860454ms","start":"2026-08-04T15:15:27.395655Z","end":"2026-08-04T15:15:27.575515Z","steps":["trace[249498153] 'agreement among raft nodes before linearized reading' (duration: 179.66685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:27.575523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.854274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-08-04T15:15:27.575623Z","caller":"traceutil/trace.go:171","msg":"trace[1480024528] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"130.988036ms","start":"2026-08-04T15:15:27.444611Z","end":"2026-08-04T15:15:27.575599Z","steps":["trace[1480024528] 'agreement among raft nodes before linearized reading' (duration: 130.683681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:27.575704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.251301Z","time spent":"323.88633ms","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:15:27.575769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.382702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-04T15:15:27.575837Z","caller":"traceutil/trace.go:171","msg":"trace[697873501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:350; }","duration":"230.951692ms","start":"2026-08-04T15:15:27.344871Z","end":"2026-08-04T15:15:27.575823Z","steps":["trace[697873501] 'agreement among raft nodes before linearized reading' (duration: 230.324271ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:27.576197Z","caller":"traceutil/trace.go:171","msg":"trace[29330742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:350; }","duration":"280.018243ms","start":"2026-08-04T15:15:27.295348Z","end":"2026-08-04T15:15:27.575366Z","steps":["trace[29330742] 'agreement among raft nodes before linearized reading' (duration: 279.771159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:28.081522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620628341032136986,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:15:28.816423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.967855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:28.816582Z","caller":"traceutil/trace.go:171","msg":"trace[407740493] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.235327524s","start":"2026-08-04T15:15:27.581229Z","end":"2026-08-04T15:15:28.816557Z","steps":["trace[407740493] 'process raft request' (duration: 742.969082ms)","trace[407740493] 'compare' (duration: 491.576258ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:28.816661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.581202Z","time spent":"1.235426226s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:15:28.852579Z","caller":"traceutil/trace.go:171","msg":"trace[811784154] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"1.268158277s","start":"2026-08-04T15:15:27.584388Z","end":"2026-08-04T15:15:28.852546Z","steps":["trace[811784154] 'process raft request' (duration: 1.267923603s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:28.852898Z","caller":"traceutil/trace.go:171","msg":"trace[116157883] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:359; }","duration":"1.271569129s","start":"2026-08-04T15:15:27.5813Z","end":"2026-08-04T15:15:28.852869Z","steps":["trace[116157883] 'read index received' (duration: 743.074474ms)","trace[116157883] 'applied index is now lower than readState.Index' (duration: 528.493005ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:28.852989Z","caller":"traceutil/trace.go:171","msg":"trace[422373129] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"1.267362681s","start":"2026-08-04T15:15:27.585604Z","end":"2026-08-04T15:15:28.852967Z","steps":["trace[422373129] 'process raft request' (duration: 1.266869893s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:28.853083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271765242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"warn","ts":"2026-08-04T15:15:28.853106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.584371Z","time spent":"1.26831498s","remote":"127.0.0.1:55032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:28.852982Z","caller":"traceutil/trace.go:171","msg":"trace[1966221502] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"1.265688841s","start":"2026-08-04T15:15:27.587257Z","end":"2026-08-04T15:15:28.852946Z","steps":["trace[1966221502] 'process raft request' (duration: 1.265496618s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:28.853141Z","caller":"traceutil/trace.go:171","msg":"trace[1426749793] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:354; }","duration":"1.271867074s","start":"2026-08-04T15:15:27.581258Z","end":"2026-08-04T15:15:28.853125Z","steps":["trace[1426749793] 'agreement among raft nodes before linearized reading' (duration: 1.271737552s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:28.853138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.585597Z","time spent":"1.267443973s","remote":"127.0.0.1:55032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:28.853201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.581241Z","time spent":"1.271949235s","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7318,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-04T15:15:28.853358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.58725Z","time spent":"1.265931535s","remote":"127.0.0.1:55032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:29.519106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.290872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:15:29.519445Z","caller":"traceutil/trace.go:171","msg":"trace[1763011421] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"997.445277ms","start":"2026-08-04T15:15:28.521947Z","end":"2026-08-04T15:15:29.519393Z","steps":["trace[1763011421] 'process raft request' (duration: 697.779768ms)","trace[1763011421] 'compare' (duration: 299.16061ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:29.519602Z","caller":"traceutil/trace.go:171","msg":"trace[273132471] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"697.790036ms","start":"2026-08-04T15:15:28.821771Z","end":"2026-08-04T15:15:29.519561Z","steps":["trace[273132471] 'process raft request' (duration: 697.47543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.519691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.521913Z","time spent":"997.644101ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:29.519769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.821744Z","time spent":"697.947628ms","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:15:29.519751Z","caller":"traceutil/trace.go:171","msg":"trace[1699954567] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"666.734557ms","start":"2026-08-04T15:15:28.852966Z","end":"2026-08-04T15:15:29.5197Z","steps":["trace[1699954567] 'read index received' (duration: 366.719262ms)","trace[1699954567] 'applied index is now lower than readState.Index' (duration: 300.012435ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:15:29.519843Z","caller":"traceutil/trace.go:171","msg":"trace[974699560] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"656.581661ms","start":"2026-08-04T15:15:28.863034Z","end":"2026-08-04T15:15:29.519616Z","steps":["trace[974699560] 'process raft request' (duration: 656.5315ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:29.5198Z","caller":"traceutil/trace.go:171","msg":"trace[1901310353] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"658.961715ms","start":"2026-08-04T15:15:28.860761Z","end":"2026-08-04T15:15:29.519722Z","steps":["trace[1901310353] 'process raft request' (duration: 658.675479ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:29.519938Z","caller":"traceutil/trace.go:171","msg":"trace[317757179] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"657.185553ms","start":"2026-08-04T15:15:28.862591Z","end":"2026-08-04T15:15:29.519776Z","steps":["trace[317757179] 'process raft request' (duration: 656.932387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.520008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.863022Z","time spent":"656.882098ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:29.520118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.860734Z","time spent":"659.23022ms","remote":"127.0.0.1:55032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:29.520166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.862574Z","time spent":"657.448828ms","remote":"127.0.0.1:55032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:15:29.520336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.132003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-04T15:15:29.520241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.859401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-04T15:15:29.5204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824321024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-04T15:15:29.520435Z","caller":"traceutil/trace.go:171","msg":"trace[615559695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:359; }","duration":"744.107085ms","start":"2026-08-04T15:15:28.77631Z","end":"2026-08-04T15:15:29.520417Z","steps":["trace[615559695] 'agreement among raft nodes before linearized reading' (duration: 743.640487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.520424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920862776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-08-04T15:15:29.520465Z","caller":"traceutil/trace.go:171","msg":"trace[880050227] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:359; }","duration":"1.824439975s","start":"2026-08-04T15:15:27.69601Z","end":"2026-08-04T15:15:29.52045Z","steps":["trace[880050227] 'agreement among raft nodes before linearized reading' (duration: 1.824340313s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.520486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.776271Z","time spent":"744.200366ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:15:29.520511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.695982Z","time spent":"1.824521627s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-08-04T15:15:29.520497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.924671187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-08-04T15:15:29.520547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.875552543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-04T15:15:29.520557Z","caller":"traceutil/trace.go:171","msg":"trace[457184714] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.924779228s","start":"2026-08-04T15:15:27.595769Z","end":"2026-08-04T15:15:29.520548Z","steps":["trace[457184714] 'agreement among raft nodes before linearized reading' (duration: 1.924664726s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:29.520586Z","caller":"traceutil/trace.go:171","msg":"trace[1134726408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.875614585s","start":"2026-08-04T15:15:27.64496Z","end":"2026-08-04T15:15:29.520575Z","steps":["trace[1134726408] 'agreement among raft nodes before linearized reading' (duration: 1.875550694s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.520624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.644949Z","time spent":"1.875666846s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-08-04T15:15:29.520622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.59576Z","time spent":"1.924826219s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-08-04T15:15:29.52072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574437483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-04T15:15:29.520768Z","caller":"traceutil/trace.go:171","msg":"trace[1699567587] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.574534835s","start":"2026-08-04T15:15:27.946222Z","end":"2026-08-04T15:15:29.520757Z","steps":["trace[1699567587] 'agreement among raft nodes before linearized reading' (duration: 1.574423643s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.520807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.946203Z","time spent":"1.574595086s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-08-04T15:15:29.52084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920671882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-2h1zsa\" ","response":"range_response_count:1 size:540"} {"level":"warn","ts":"2026-08-04T15:15:29.520845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.441822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-04T15:15:29.520937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768600739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:15:29.520939Z","caller":"traceutil/trace.go:171","msg":"trace[1597378427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:359; }","duration":"440.562525ms","start":"2026-08-04T15:15:29.080342Z","end":"2026-08-04T15:15:29.520905Z","steps":["trace[1597378427] 'agreement among raft nodes before linearized reading' (duration: 440.460603ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:29.520972Z","caller":"traceutil/trace.go:171","msg":"trace[481755676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:359; }","duration":"1.768659151s","start":"2026-08-04T15:15:27.752304Z","end":"2026-08-04T15:15:29.520963Z","steps":["trace[481755676] 'agreement among raft nodes before linearized reading' (duration: 1.76861057s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.52101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.752285Z","time spent":"1.768711221s","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:15:29.52103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769626078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-04T15:15:29.521066Z","caller":"traceutil/trace.go:171","msg":"trace[1410218150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.769710719s","start":"2026-08-04T15:15:27.751345Z","end":"2026-08-04T15:15:29.521056Z","steps":["trace[1410218150] 'agreement among raft nodes before linearized reading' (duration: 1.769633068s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.521098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.751322Z","time spent":"1.769769571s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-08-04T15:15:29.521069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723601343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-08-04T15:15:29.521038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:29.080306Z","time spent":"440.664267ms","remote":"127.0.0.1:54936","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":"info","ts":"2026-08-04T15:15:29.520385Z","caller":"traceutil/trace.go:171","msg":"trace[1890496125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:359; }","duration":"948.221065ms","start":"2026-08-04T15:15:28.57215Z","end":"2026-08-04T15:15:29.520371Z","steps":["trace[1890496125] 'agreement among raft nodes before linearized reading' (duration: 948.147634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.521063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675253525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-08-04T15:15:29.521211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625114035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-08-04T15:15:29.521249Z","caller":"traceutil/trace.go:171","msg":"trace[396502161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.67549322s","start":"2026-08-04T15:15:27.845747Z","end":"2026-08-04T15:15:29.52124Z","steps":["trace[396502161] 'agreement among raft nodes before linearized reading' (duration: 1.675195273s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.521256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:28.572125Z","time spent":"949.117453ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-04T15:15:29.521296Z","caller":"traceutil/trace.go:171","msg":"trace[2117430804] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.625227176s","start":"2026-08-04T15:15:27.896042Z","end":"2026-08-04T15:15:29.521269Z","steps":["trace[2117430804] 'agreement among raft nodes before linearized reading' (duration: 1.625120924s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.521296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.845738Z","time spent":"1.67554502s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-08-04T15:15:29.520876Z","caller":"traceutil/trace.go:171","msg":"trace[1199580484] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-2h1zsa; range_end:; response_count:1; response_revision:359; }","duration":"1.920722573s","start":"2026-08-04T15:15:27.600143Z","end":"2026-08-04T15:15:29.520865Z","steps":["trace[1199580484] 'agreement among raft nodes before linearized reading' (duration: 1.920656672s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.527321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.600134Z","time spent":"1.927162741s","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-2h1zsa\" "} {"level":"info","ts":"2026-08-04T15:15:29.520487Z","caller":"traceutil/trace.go:171","msg":"trace[640654700] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:359; }","duration":"1.920942288s","start":"2026-08-04T15:15:27.59953Z","end":"2026-08-04T15:15:29.520473Z","steps":["trace[640654700] 'agreement among raft nodes before linearized reading' (duration: 1.920835596s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.5275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.599521Z","time spent":"1.927970316s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4220,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-04T15:15:29.521361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.896017Z","time spent":"1.625312848s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-08-04T15:15:29.521183Z","caller":"traceutil/trace.go:171","msg":"trace[1407285851] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:359; }","duration":"1.723752816s","start":"2026-08-04T15:15:27.797418Z","end":"2026-08-04T15:15:29.521171Z","steps":["trace[1407285851] 'agreement among raft nodes before linearized reading' (duration: 1.723587663s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.527813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:27.7974Z","time spent":"1.730392598s","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-08-04T15:15:29.522227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.890379ms","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-08-04T15:15:29.529784Z","caller":"traceutil/trace.go:171","msg":"trace[868886720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:359; }","duration":"360.705025ms","start":"2026-08-04T15:15:29.16728Z","end":"2026-08-04T15:15:29.527985Z","steps":["trace[868886720] 'agreement among raft nodes before linearized reading' (duration: 353.949312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:29.529896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:29.167258Z","time spent":"362.620661ms","remote":"127.0.0.1:54936","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-08-04T15:15:58.849419Z","caller":"traceutil/trace.go:171","msg":"trace[1790008261] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"397.707947ms","start":"2026-08-04T15:15:58.451679Z","end":"2026-08-04T15:15:58.849387Z","steps":["trace[1790008261] 'read index received' (duration: 396.286182ms)","trace[1790008261] 'applied index is now lower than readState.Index' (duration: 1.420655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:58.849662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.952851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-08-04T15:15:58.849706Z","caller":"traceutil/trace.go:171","msg":"trace[2007430079] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:625; }","duration":"398.075864ms","start":"2026-08-04T15:15:58.451618Z","end":"2026-08-04T15:15:58.849694Z","steps":["trace[2007430079] 'agreement among raft nodes before linearized reading' (duration: 397.88173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:58.849745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:58.451595Z","time spent":"398.139716ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10894,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-04T15:15:58.849876Z","caller":"traceutil/trace.go:171","msg":"trace[1418278594] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"409.700206ms","start":"2026-08-04T15:15:58.440166Z","end":"2026-08-04T15:15:58.849866Z","steps":["trace[1418278594] 'process raft request' (duration: 407.755652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:58.849975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:58.440152Z","time spent":"409.753976ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:58.854895Z","caller":"traceutil/trace.go:171","msg":"trace[2110061911] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"329.369222ms","start":"2026-08-04T15:15:58.525487Z","end":"2026-08-04T15:15:58.854857Z","steps":["trace[2110061911] 'process raft request' (duration: 329.110948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:58.855061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:58.525468Z","time spent":"329.510244ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:15:58.855425Z","caller":"traceutil/trace.go:171","msg":"trace[1974913701] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"130.692387ms","start":"2026-08-04T15:15:58.724707Z","end":"2026-08-04T15:15:58.8554Z","steps":["trace[1974913701] 'process raft request' (duration: 130.650186ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:58.855578Z","caller":"traceutil/trace.go:171","msg":"trace[819264892] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"256.178263ms","start":"2026-08-04T15:15:58.599387Z","end":"2026-08-04T15:15:58.855565Z","steps":["trace[819264892] 'process raft request' (duration: 255.47881ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:59.378841Z","caller":"traceutil/trace.go:171","msg":"trace[2117742392] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"304.108406ms","start":"2026-08-04T15:15:59.074698Z","end":"2026-08-04T15:15:59.378807Z","steps":["trace[2117742392] 'read index received' (duration: 302.239424ms)","trace[2117742392] 'applied index is now lower than readState.Index' (duration: 1.867292ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:15:59.379147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.408982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-08-04T15:15:59.379193Z","caller":"traceutil/trace.go:171","msg":"trace[1178008796] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:637; }","duration":"304.512924ms","start":"2026-08-04T15:15:59.074661Z","end":"2026-08-04T15:15:59.379174Z","steps":["trace[1178008796] 'agreement among raft nodes before linearized reading' (duration: 304.28418ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:59.379193Z","caller":"traceutil/trace.go:171","msg":"trace[1780688114] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"309.889657ms","start":"2026-08-04T15:15:59.069232Z","end":"2026-08-04T15:15:59.379122Z","steps":["trace[1780688114] 'process raft request' (duration: 307.621077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:15:59.379245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:59.074644Z","time spent":"304.591625ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":65238,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-04T15:15:59.379477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:15:59.069223Z","time spent":"310.0948ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:15:59.387348Z","caller":"traceutil/trace.go:171","msg":"trace[854265402] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"272.058702ms","start":"2026-08-04T15:15:59.115266Z","end":"2026-08-04T15:15:59.387325Z","steps":["trace[854265402] 'process raft request' (duration: 271.776007ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:15:59.387628Z","caller":"traceutil/trace.go:171","msg":"trace[1207847854] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"261.927825ms","start":"2026-08-04T15:15:59.125691Z","end":"2026-08-04T15:15:59.387619Z","steps":["trace[1207847854] 'process raft request' (duration: 261.604579ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:16:01.255434Z","caller":"traceutil/trace.go:171","msg":"trace[1911120204] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"102.000171ms","start":"2026-08-04T15:16:01.153403Z","end":"2026-08-04T15:16:01.255403Z","steps":["trace[1911120204] 'process raft request' (duration: 101.754897ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:16:05.457991Z","caller":"traceutil/trace.go:171","msg":"trace[552974832] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"569.179617ms","start":"2026-08-04T15:16:04.888755Z","end":"2026-08-04T15:16:05.457935Z","steps":["trace[552974832] 'process raft request' (duration: 557.783831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:05.458206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:04.888742Z","time spent":"569.38024ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:16:05.458227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.570266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:16:05.458319Z","caller":"traceutil/trace.go:171","msg":"trace[576401124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"472.689539ms","start":"2026-08-04T15:16:04.985608Z","end":"2026-08-04T15:16:05.458298Z","steps":["trace[576401124] 'agreement among raft nodes before linearized reading' (duration: 472.395903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:05.458379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:04.985526Z","time spent":"472.843201ms","remote":"127.0.0.1:54718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:16:05.457961Z","caller":"traceutil/trace.go:171","msg":"trace[651381426] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"472.279141ms","start":"2026-08-04T15:16:04.985627Z","end":"2026-08-04T15:16:05.457906Z","steps":["trace[651381426] 'read index received' (duration: 460.784093ms)","trace[651381426] 'applied index is now lower than readState.Index' (duration: 11.493408ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:16:08.33216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620628341032137676,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:16:08.833087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620628341032137676,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-04T15:16:08.91136Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100885506s","expected-duration":"1s"} {"level":"info","ts":"2026-08-04T15:16:08.911754Z","caller":"traceutil/trace.go:171","msg":"trace[1879848129] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"1.080695629s","start":"2026-08-04T15:16:07.831032Z","end":"2026-08-04T15:16:08.911727Z","steps":["trace[1879848129] 'read index received' (duration: 1.080438345s)","trace[1879848129] 'applied index is now lower than readState.Index' (duration: 256.334µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:16:08.911872Z","caller":"traceutil/trace.go:171","msg":"trace[284521788] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.101461305s","start":"2026-08-04T15:16:07.810368Z","end":"2026-08-04T15:16:08.911829Z","steps":["trace[284521788] 'process raft request' (duration: 1.10117567s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:08.912059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:07.810354Z","time spent":"1.101604318s","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:16:08.91218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081128786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-04T15:16:08.912145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.898234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:16:08.912235Z","caller":"traceutil/trace.go:171","msg":"trace[1186527605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"1.081257088s","start":"2026-08-04T15:16:07.830964Z","end":"2026-08-04T15:16:08.912222Z","steps":["trace[1186527605] 'agreement among raft nodes before linearized reading' (duration: 1.080868442s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:16:08.912269Z","caller":"traceutil/trace.go:171","msg":"trace[38588857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"924.076407ms","start":"2026-08-04T15:16:07.988164Z","end":"2026-08-04T15:16:08.912241Z","steps":["trace[38588857] 'agreement among raft nodes before linearized reading' (duration: 923.843603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:08.912283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:07.830928Z","time spent":"1.08134646s","remote":"127.0.0.1:54936","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-08-04T15:16:08.912332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:07.988152Z","time spent":"924.170458ms","remote":"127.0.0.1:54718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:16:08.912413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01016038s","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-08-04T15:16:08.912532Z","caller":"traceutil/trace.go:171","msg":"trace[45640283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"1.010495407s","start":"2026-08-04T15:16:07.902017Z","end":"2026-08-04T15:16:08.912512Z","steps":["trace[45640283] 'agreement among raft nodes before linearized reading' (duration: 1.009994718s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:08.912484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.314841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-04T15:16:08.912615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:07.901976Z","time spent":"1.01062833s","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-04T15:16:08.912681Z","caller":"traceutil/trace.go:171","msg":"trace[2105522347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"987.541104ms","start":"2026-08-04T15:16:07.925093Z","end":"2026-08-04T15:16:08.912634Z","steps":["trace[2105522347] 'agreement among raft nodes before linearized reading' (duration: 987.109446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:08.912781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:07.925061Z","time spent":"987.685067ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T15:16:08.912467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.794538ms","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-08-04T15:16:08.9129Z","caller":"traceutil/trace.go:171","msg":"trace[595892688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"988.241656ms","start":"2026-08-04T15:16:07.924642Z","end":"2026-08-04T15:16:08.912884Z","steps":["trace[595892688] 'agreement among raft nodes before linearized reading' (duration: 987.757557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:08.912982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:07.924622Z","time spent":"988.342058ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:16:25.529653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620628341032137899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:16:25.535237Z","caller":"traceutil/trace.go:171","msg":"trace[1879049541] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"506.623001ms","start":"2026-08-04T15:16:25.028583Z","end":"2026-08-04T15:16:25.535206Z","steps":["trace[1879049541] 'read index received' (duration: 506.440648ms)","trace[1879049541] 'applied index is now lower than readState.Index' (duration: 180.763µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:16:25.535376Z","caller":"traceutil/trace.go:171","msg":"trace[111523214] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"537.680561ms","start":"2026-08-04T15:16:24.997683Z","end":"2026-08-04T15:16:25.535363Z","steps":["trace[111523214] 'process raft request' (duration: 537.344696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.535502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:24.997662Z","time spent":"537.747843ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:16:25.535716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.315784ms","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-08-04T15:16:25.535783Z","caller":"traceutil/trace.go:171","msg":"trace[1374083625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"506.439617ms","start":"2026-08-04T15:16:25.029322Z","end":"2026-08-04T15:16:25.535762Z","steps":["trace[1374083625] 'agreement among raft nodes before linearized reading' (duration: 506.235403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.535822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.23539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-04T15:16:25.535858Z","caller":"traceutil/trace.go:171","msg":"trace[1785149889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:820; }","duration":"507.295371ms","start":"2026-08-04T15:16:25.028553Z","end":"2026-08-04T15:16:25.535848Z","steps":["trace[1785149889] 'agreement among raft nodes before linearized reading' (duration: 507.166259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.535897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:25.028538Z","time spent":"507.352722ms","remote":"127.0.0.1:54936","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-08-04T15:16:25.535909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.20845ms","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-08-04T15:16:25.535939Z","caller":"traceutil/trace.go:171","msg":"trace[1714195527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"501.26219ms","start":"2026-08-04T15:16:25.034667Z","end":"2026-08-04T15:16:25.535929Z","steps":["trace[1714195527] 'agreement among raft nodes before linearized reading' (duration: 501.178969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.535971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:25.034648Z","time spent":"501.317082ms","remote":"127.0.0.1:54936","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-08-04T15:16:25.536126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.568795ms","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-08-04T15:16:25.536158Z","caller":"traceutil/trace.go:171","msg":"trace[1510597718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"504.618486ms","start":"2026-08-04T15:16:25.031531Z","end":"2026-08-04T15:16:25.536149Z","steps":["trace[1510597718] 'agreement among raft nodes before linearized reading' (duration: 504.540335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.536189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:25.030969Z","time spent":"505.212936ms","remote":"127.0.0.1:54936","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-08-04T15:16:25.535826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:25.02927Z","time spent":"506.547299ms","remote":"127.0.0.1:54936","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-08-04T15:16:25.536207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.586684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:16:25.536292Z","caller":"traceutil/trace.go:171","msg":"trace[909094432] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:820; }","duration":"477.715246ms","start":"2026-08-04T15:16:25.058557Z","end":"2026-08-04T15:16:25.536272Z","steps":["trace[909094432] 'agreement among raft nodes before linearized reading' (duration: 477.560112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.536356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:25.058542Z","time spent":"477.803138ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:16:25.538427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.453358ms","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-08-04T15:16:25.538758Z","caller":"traceutil/trace.go:171","msg":"trace[1046920593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"503.994206ms","start":"2026-08-04T15:16:25.034727Z","end":"2026-08-04T15:16:25.538721Z","steps":["trace[1046920593] 'agreement among raft nodes before linearized reading' (duration: 500.984126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:16:25.538963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:16:25.034716Z","time spent":"504.22474ms","remote":"127.0.0.1:54936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-04T15:16:38.670943Z","caller":"traceutil/trace.go:171","msg":"trace[133723899] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"264.271753ms","start":"2026-08-04T15:16:38.406623Z","end":"2026-08-04T15:16:38.670895Z","steps":["trace[133723899] 'process raft request' (duration: 225.636932ms)","trace[133723899] 'compare' (duration: 38.418596ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:18:26.528348Z","caller":"traceutil/trace.go:171","msg":"trace[1424802804] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"100.897221ms","start":"2026-08-04T15:18:26.427421Z","end":"2026-08-04T15:18:26.528318Z","steps":["trace[1424802804] 'process raft request' (duration: 100.730299ms)"],"step_count":1}