{"level":"warn","ts":"2026-07-24T05:12:13.689289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T05:12:13.689378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-07-24T05:12:13.68945Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T05:12:13.689467Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-24T05:12:13.689484Z","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-07-24T05:12:13.690159Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-07-24T05:12:13.690233Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-07-24T05:12:13.693772Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.281443ms"} {"level":"info","ts":"2026-07-24T05:12:13.69923Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-07-24T05:12:13.699313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T05:12:13.699335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-07-24T05:12:13.699352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T05:12:13.699365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-07-24T05:12:13.699405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-07-24T05:12:13.706783Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T05:12:13.707926Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T05:12:13.709035Z","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-07-24T05:12:13.711282Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T05:12:13.715007Z","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-07-24T05:12:13.716426Z","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-07-24T05:12:13.71647Z","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-07-24T05:12:13.715455Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T05:12:13.718062Z","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-07-24T05:12:13.718199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-07-24T05:12:13.718192Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-24T05:12:13.718259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-24T05:12:13.71841Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-24T05:12:13.718651Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T05:12:13.718701Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T05:12:14.600552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T05:12:14.600622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T05:12:14.600655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-07-24T05:12:14.600672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-07-24T05:12:14.600679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-24T05:12:14.600692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-07-24T05:12:14.600705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-24T05:12:14.602342Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T05:12:14.602947Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:12:14.603897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T05:12:14.604308Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T05:12:14.604535Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T05:12:14.604552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T05:12:14.604576Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:12:14.604641Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:12:14.604662Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T05:12:14.607064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T05:12:14.613749Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"warn","ts":"2026-07-24T05:12:19.411613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280482724s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:12:19.411819Z","caller":"traceutil/trace.go:171","msg":"trace[843057105] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"1.802630156s","start":"2026-07-24T05:12:17.609143Z","end":"2026-07-24T05:12:19.411773Z","steps":["trace[843057105] 'process raft request' (duration: 521.266291ms)","trace[843057105] 'compare' (duration: 1.280034063s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:12:19.411932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392830395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:12:19.411984Z","caller":"traceutil/trace.go:171","msg":"trace[1354347650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:165; }","duration":"1.39293346s","start":"2026-07-24T05:12:18.019034Z","end":"2026-07-24T05:12:19.411968Z","steps":["trace[1354347650] 'agreement among raft nodes before linearized reading' (duration: 1.392817304s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:19.412032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:18.019007Z","time spent":"1.393015114s","remote":"127.0.0.1:39588","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-07-24T05:12:19.41202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:17.609126Z","time spent":"1.802799353s","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:12:19.411801Z","caller":"traceutil/trace.go:171","msg":"trace[489909523] linearizableReadLoop","detail":"{readStateIndex:169; appliedIndex:168; }","duration":"1.392643036s","start":"2026-07-24T05:12:18.019104Z","end":"2026-07-24T05:12:19.411747Z","steps":["trace[489909523] 'read index received' (duration: 111.187287ms)","trace[489909523] 'applied index is now lower than readState.Index' (duration: 1.281454339s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:12:19.412525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.18665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:12:19.412567Z","caller":"traceutil/trace.go:171","msg":"trace[950440781] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:165; }","duration":"581.264023ms","start":"2026-07-24T05:12:18.83129Z","end":"2026-07-24T05:12:19.412554Z","steps":["trace[950440781] 'agreement among raft nodes before linearized reading' (duration: 581.20049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:19.412602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:18.831264Z","time spent":"581.330777ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-07-24T05:12:19.412796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.160739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:12:19.412801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.55512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:12:19.412833Z","caller":"traceutil/trace.go:171","msg":"trace[834266768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:165; }","duration":"317.238762ms","start":"2026-07-24T05:12:19.095586Z","end":"2026-07-24T05:12:19.412825Z","steps":["trace[834266768] 'agreement among raft nodes before linearized reading' (duration: 317.1797ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:19.412852Z","caller":"traceutil/trace.go:171","msg":"trace[1128129557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:165; }","duration":"217.670195ms","start":"2026-07-24T05:12:19.195167Z","end":"2026-07-24T05:12:19.412837Z","steps":["trace[1128129557] 'agreement among raft nodes before linearized reading' (duration: 217.534629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:19.412862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:19.095562Z","time spent":"317.293815ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T05:12:20.059205Z","caller":"traceutil/trace.go:171","msg":"trace[430039079] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"641.980403ms","start":"2026-07-24T05:12:19.417199Z","end":"2026-07-24T05:12:20.059179Z","steps":["trace[430039079] 'process raft request' (duration: 641.846437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:20.059392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:19.41718Z","time spent":"642.1322ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:12:20.063201Z","caller":"traceutil/trace.go:171","msg":"trace[103226322] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"645.788248ms","start":"2026-07-24T05:12:19.417396Z","end":"2026-07-24T05:12:20.063184Z","steps":["trace[103226322] 'process raft request' (duration: 645.600929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:20.063264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:19.417374Z","time spent":"645.863471ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:12:20.063336Z","caller":"traceutil/trace.go:171","msg":"trace[13944482] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"451.893466ms","start":"2026-07-24T05:12:19.611378Z","end":"2026-07-24T05:12:20.063271Z","steps":["trace[13944482] 'process raft request' (duration: 451.778741ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:20.063227Z","caller":"traceutil/trace.go:171","msg":"trace[1388387096] linearizableReadLoop","detail":"{readStateIndex:172; appliedIndex:169; }","duration":"475.284452ms","start":"2026-07-24T05:12:19.58791Z","end":"2026-07-24T05:12:20.063195Z","steps":["trace[1388387096] 'read index received' (duration: 471.226195ms)","trace[1388387096] 'applied index is now lower than readState.Index' (duration: 4.056926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:12:20.063548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:19.611353Z","time spent":"452.090686ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T05:12:20.063549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.598786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:12:20.063618Z","caller":"traceutil/trace.go:171","msg":"trace[1586567007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:169; }","duration":"475.748972ms","start":"2026-07-24T05:12:19.587855Z","end":"2026-07-24T05:12:20.063604Z","steps":["trace[1586567007] 'agreement among raft nodes before linearized reading' (duration: 475.574184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:20.063633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.260127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:12:20.063654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:19.587839Z","time spent":"475.808735ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T05:12:20.063669Z","caller":"traceutil/trace.go:171","msg":"trace[202233625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:169; }","duration":"291.355591ms","start":"2026-07-24T05:12:19.772301Z","end":"2026-07-24T05:12:20.063657Z","steps":["trace[202233625] 'agreement among raft nodes before linearized reading' (duration: 291.285377ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:20.063746Z","caller":"traceutil/trace.go:171","msg":"trace[109909343] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"644.005794ms","start":"2026-07-24T05:12:19.419404Z","end":"2026-07-24T05:12:20.063409Z","steps":["trace[109909343] 'process raft request' (duration: 643.713041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:20.063851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:19.419389Z","time spent":"644.390522ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:12:58.861601Z","caller":"traceutil/trace.go:171","msg":"trace[14465438] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"402.343026ms","start":"2026-07-24T05:12:58.459222Z","end":"2026-07-24T05:12:58.861565Z","steps":["trace[14465438] 'process raft request' (duration: 402.12771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:58.862081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:58.459201Z","time spent":"402.505931ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:12:59.298426Z","caller":"traceutil/trace.go:171","msg":"trace[1331595532] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"107.216266ms","start":"2026-07-24T05:12:59.19117Z","end":"2026-07-24T05:12:59.298386Z","steps":["trace[1331595532] 'process raft request' (duration: 106.866175ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.298552Z","caller":"traceutil/trace.go:171","msg":"trace[1869770661] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"108.953019ms","start":"2026-07-24T05:12:59.189539Z","end":"2026-07-24T05:12:59.298492Z","steps":["trace[1869770661] 'process raft request' (duration: 108.379582ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.298727Z","caller":"traceutil/trace.go:171","msg":"trace[1217022968] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"107.228527ms","start":"2026-07-24T05:12:59.191483Z","end":"2026-07-24T05:12:59.298712Z","steps":["trace[1217022968] 'process raft request' (duration: 106.586437ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.298826Z","caller":"traceutil/trace.go:171","msg":"trace[1338991434] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"105.240586ms","start":"2026-07-24T05:12:59.193576Z","end":"2026-07-24T05:12:59.298816Z","steps":["trace[1338991434] 'process raft request' (duration: 104.539114ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.299014Z","caller":"traceutil/trace.go:171","msg":"trace[905105813] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"100.699029ms","start":"2026-07-24T05:12:59.198257Z","end":"2026-07-24T05:12:59.298956Z","steps":["trace[905105813] 'process raft request' (duration: 99.950036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:59.573962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.600878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:12:59.574185Z","caller":"traceutil/trace.go:171","msg":"trace[1046787064] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"346.147939ms","start":"2026-07-24T05:12:59.228Z","end":"2026-07-24T05:12:59.574148Z","steps":["trace[1046787064] 'process raft request' (duration: 127.221411ms)","trace[1046787064] 'compare' (duration: 218.382681ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:12:59.574287Z","caller":"traceutil/trace.go:171","msg":"trace[444005005] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"337.458076ms","start":"2026-07-24T05:12:59.236816Z","end":"2026-07-24T05:12:59.574274Z","steps":["trace[444005005] 'process raft request' (duration: 337.292051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:12:59.5743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.227976Z","time spent":"346.275873ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:12:59.574393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.236796Z","time spent":"337.547679ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:12:59.591887Z","caller":"traceutil/trace.go:171","msg":"trace[1054095440] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"282.850343ms","start":"2026-07-24T05:12:59.309008Z","end":"2026-07-24T05:12:59.591859Z","steps":["trace[1054095440] 'process raft request' (duration: 282.730279ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.591885Z","caller":"traceutil/trace.go:171","msg":"trace[350286033] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"285.152072ms","start":"2026-07-24T05:12:59.306682Z","end":"2026-07-24T05:12:59.591834Z","steps":["trace[350286033] 'process raft request' (duration: 285.004807ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.591963Z","caller":"traceutil/trace.go:171","msg":"trace[927965706] transaction","detail":"{read_only:false; number_of_response:0; response_revision:535; }","duration":"282.834552ms","start":"2026-07-24T05:12:59.309109Z","end":"2026-07-24T05:12:59.591944Z","steps":["trace[927965706] 'process raft request' (duration: 282.648947ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.592109Z","caller":"traceutil/trace.go:171","msg":"trace[319096041] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"276.878412ms","start":"2026-07-24T05:12:59.315213Z","end":"2026-07-24T05:12:59.592091Z","steps":["trace[319096041] 'process raft request' (duration: 276.607683ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.592204Z","caller":"traceutil/trace.go:171","msg":"trace[1499512188] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"256.383321ms","start":"2026-07-24T05:12:59.335808Z","end":"2026-07-24T05:12:59.592191Z","steps":["trace[1499512188] 'process raft request' (duration: 256.114043ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:59.592207Z","caller":"traceutil/trace.go:171","msg":"trace[1787267847] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"251.775342ms","start":"2026-07-24T05:12:59.340419Z","end":"2026-07-24T05:12:59.592195Z","steps":["trace[1787267847] 'process raft request' (duration: 251.621117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.16935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119931649755066,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:13:00.67024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119931649755066,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:13:00.850702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.181741851s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T05:13:00.859558Z","caller":"traceutil/trace.go:171","msg":"trace[491684999] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.206131016s","start":"2026-07-24T05:12:59.653392Z","end":"2026-07-24T05:13:00.859523Z","steps":["trace[491684999] 'process raft request' (duration: 1.1975854s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.859726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.653347Z","time spent":"1.206272641s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:00.860016Z","caller":"traceutil/trace.go:171","msg":"trace[109505050] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.197740883s","start":"2026-07-24T05:12:59.662229Z","end":"2026-07-24T05:13:00.85997Z","steps":["trace[109505050] 'process raft request' (duration: 1.197345421s)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:00.865299Z","caller":"traceutil/trace.go:171","msg":"trace[1213549734] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.184927824s","start":"2026-07-24T05:12:59.680345Z","end":"2026-07-24T05:13:00.865272Z","steps":["trace[1213549734] 'process raft request' (duration: 1.183672346s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.865474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.680333Z","time spent":"1.185031187s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:00.868284Z","caller":"traceutil/trace.go:171","msg":"trace[1370064489] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.187851962s","start":"2026-07-24T05:12:59.680414Z","end":"2026-07-24T05:13:00.868266Z","steps":["trace[1370064489] 'process raft request' (duration: 1.184059258s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.86835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.680409Z","time spent":"1.187901463s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:13:00.868754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.662187Z","time spent":"1.197909317s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:00.869219Z","caller":"traceutil/trace.go:171","msg":"trace[718613155] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:566; }","duration":"1.20038462s","start":"2026-07-24T05:12:59.668805Z","end":"2026-07-24T05:13:00.86919Z","steps":["trace[718613155] 'read index received' (duration: 1.182141753s)","trace[718613155] 'applied index is now lower than readState.Index' (duration: 18.241277ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:00.869394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275076242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-07-24T05:13:00.869424Z","caller":"traceutil/trace.go:171","msg":"trace[1967720094] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:544; }","duration":"1.275142813s","start":"2026-07-24T05:12:59.594267Z","end":"2026-07-24T05:13:00.869409Z","steps":["trace[1967720094] 'agreement among raft nodes before linearized reading' (duration: 1.275010789s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.869443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.59426Z","time spent":"1.275177225s","remote":"127.0.0.1:40158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1018,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-24T05:13:00.869686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275410351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-07-24T05:13:00.869771Z","caller":"traceutil/trace.go:171","msg":"trace[1598038048] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:544; }","duration":"1.275502994s","start":"2026-07-24T05:12:59.594241Z","end":"2026-07-24T05:13:00.869744Z","steps":["trace[1598038048] 'agreement among raft nodes before linearized reading' (duration: 1.274964348s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.869821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.594227Z","time spent":"1.275586297s","remote":"127.0.0.1:40158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-24T05:13:00.890653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010066283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:00.89074Z","caller":"traceutil/trace.go:171","msg":"trace[836024145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.010217147s","start":"2026-07-24T05:12:59.880501Z","end":"2026-07-24T05:13:00.890718Z","steps":["trace[836024145] 'agreement among raft nodes before linearized reading' (duration: 1.010051452s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.890777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:12:59.880484Z","time spent":"1.010285249s","remote":"127.0.0.1:39588","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-07-24T05:13:00.890794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.061164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T05:13:00.890806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.167567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:13:00.890829Z","caller":"traceutil/trace.go:171","msg":"trace[208173835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"586.099155ms","start":"2026-07-24T05:13:00.304722Z","end":"2026-07-24T05:13:00.890821Z","steps":["trace[208173835] 'agreement among raft nodes before linearized reading' (duration: 585.91813ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:00.890868Z","caller":"traceutil/trace.go:171","msg":"trace[1418423760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"592.307211ms","start":"2026-07-24T05:13:00.298544Z","end":"2026-07-24T05:13:00.890852Z","steps":["trace[1418423760] 'agreement among raft nodes before linearized reading' (duration: 592.073634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.890877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:00.304699Z","time spent":"586.172156ms","remote":"127.0.0.1:39856","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-07-24T05:13:00.8909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:00.298504Z","time spent":"592.390843ms","remote":"127.0.0.1:39856","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-07-24T05:13:00.890991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.395714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T05:13:00.890658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.370933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T05:13:00.891013Z","caller":"traceutil/trace.go:171","msg":"trace[479535257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"603.455486ms","start":"2026-07-24T05:13:00.287551Z","end":"2026-07-24T05:13:00.891006Z","steps":["trace[479535257] 'agreement among raft nodes before linearized reading' (duration: 603.389234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.891034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:00.287531Z","time spent":"603.498226ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-24T05:13:00.891033Z","caller":"traceutil/trace.go:171","msg":"trace[162989454] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:545; }","duration":"569.767205ms","start":"2026-07-24T05:13:00.321253Z","end":"2026-07-24T05:13:00.89102Z","steps":["trace[162989454] 'agreement among raft nodes before linearized reading' (duration: 569.344032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:00.891061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:00.321241Z","time spent":"569.811966ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-07-24T05:13:01.11856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.432539ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-24T05:13:01.119152Z","caller":"traceutil/trace.go:171","msg":"trace[1726215553] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"172.354335ms","start":"2026-07-24T05:13:00.946777Z","end":"2026-07-24T05:13:01.119131Z","steps":["trace[1726215553] 'process raft request' (duration: 172.299164ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.119198Z","caller":"traceutil/trace.go:171","msg":"trace[2048836860] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"223.84795ms","start":"2026-07-24T05:13:00.895306Z","end":"2026-07-24T05:13:01.119153Z","steps":["trace[2048836860] 'process raft request' (duration: 223.683945ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.119361Z","caller":"traceutil/trace.go:171","msg":"trace[1455923344] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"223.498819ms","start":"2026-07-24T05:13:00.895854Z","end":"2026-07-24T05:13:01.119353Z","steps":["trace[1455923344] 'process raft request' (duration: 223.160569ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.11935Z","caller":"traceutil/trace.go:171","msg":"trace[484621307] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"226.252662ms","start":"2026-07-24T05:13:00.893065Z","end":"2026-07-24T05:13:01.119318Z","steps":["trace[484621307] 'process raft request' (duration: 225.622523ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.119456Z","caller":"traceutil/trace.go:171","msg":"trace[226269145] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"226.556091ms","start":"2026-07-24T05:13:00.892891Z","end":"2026-07-24T05:13:01.119448Z","steps":["trace[226269145] 'read index received' (duration: 41.845285ms)","trace[226269145] 'applied index is now lower than readState.Index' (duration: 184.709636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:01.119519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.611712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:01.119543Z","caller":"traceutil/trace.go:171","msg":"trace[1644736818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"226.662404ms","start":"2026-07-24T05:13:00.892873Z","end":"2026-07-24T05:13:01.119535Z","steps":["trace[1644736818] 'agreement among raft nodes before linearized reading' (duration: 226.609033ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.119591Z","caller":"traceutil/trace.go:171","msg":"trace[1388972383] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"224.51324ms","start":"2026-07-24T05:13:00.895063Z","end":"2026-07-24T05:13:01.119576Z","steps":["trace[1388972383] 'process raft request' (duration: 223.83856ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.119748Z","caller":"traceutil/trace.go:171","msg":"trace[1534207628] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"224.593262ms","start":"2026-07-24T05:13:00.895142Z","end":"2026-07-24T05:13:01.119735Z","steps":["trace[1534207628] 'process raft request' (duration: 223.797409ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.190834Z","caller":"traceutil/trace.go:171","msg":"trace[138733880] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"190.023405ms","start":"2026-07-24T05:13:01.000783Z","end":"2026-07-24T05:13:01.190807Z","steps":["trace[138733880] 'process raft request' (duration: 189.821579ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.354629Z","caller":"traceutil/trace.go:171","msg":"trace[2140406359] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"223.029584ms","start":"2026-07-24T05:13:01.131573Z","end":"2026-07-24T05:13:01.354602Z","steps":["trace[2140406359] 'read index received' (duration: 59.031403ms)","trace[2140406359] 'applied index is now lower than readState.Index' (duration: 163.997201ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:01.355383Z","caller":"traceutil/trace.go:171","msg":"trace[1424776867] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"217.253073ms","start":"2026-07-24T05:13:01.138109Z","end":"2026-07-24T05:13:01.355362Z","steps":["trace[1424776867] 'process raft request' (duration: 216.426548ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.355376Z","caller":"traceutil/trace.go:171","msg":"trace[325743131] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"235.459013ms","start":"2026-07-24T05:13:01.119881Z","end":"2026-07-24T05:13:01.35534Z","steps":["trace[325743131] 'process raft request' (duration: 170.596757ms)","trace[325743131] 'compare' (duration: 63.736723ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:01.35542Z","caller":"traceutil/trace.go:171","msg":"trace[1455773087] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"220.18979ms","start":"2026-07-24T05:13:01.135184Z","end":"2026-07-24T05:13:01.355374Z","steps":["trace[1455773087] 'process raft request' (duration: 219.258393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:01.355936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.321564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:01.356059Z","caller":"traceutil/trace.go:171","msg":"trace[1525183202] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"114.242154ms","start":"2026-07-24T05:13:01.241786Z","end":"2026-07-24T05:13:01.356028Z","steps":["trace[1525183202] 'process raft request' (duration: 113.43147ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.363215Z","caller":"traceutil/trace.go:171","msg":"trace[787633961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"231.505936ms","start":"2026-07-24T05:13:01.131509Z","end":"2026-07-24T05:13:01.363015Z","steps":["trace[787633961] 'agreement among raft nodes before linearized reading' (duration: 223.910491ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:01.728759Z","caller":"traceutil/trace.go:171","msg":"trace[1301539511] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"185.18239ms","start":"2026-07-24T05:13:01.543547Z","end":"2026-07-24T05:13:01.728729Z","steps":["trace[1301539511] 'process raft request' (duration: 137.405091ms)","trace[1301539511] 'compare' (duration: 47.584353ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:02.191614Z","caller":"traceutil/trace.go:171","msg":"trace[1939592088] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"399.520645ms","start":"2026-07-24T05:13:01.792062Z","end":"2026-07-24T05:13:02.191582Z","steps":["trace[1939592088] 'process raft request' (duration: 399.160274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:02.192655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:01.792041Z","time spent":"399.672649ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:02.255311Z","caller":"traceutil/trace.go:171","msg":"trace[117866820] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"400.65809ms","start":"2026-07-24T05:13:01.854631Z","end":"2026-07-24T05:13:02.255289Z","steps":["trace[117866820] 'process raft request' (duration: 400.407943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:02.255426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:01.854587Z","time spent":"400.784153ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:02.255503Z","caller":"traceutil/trace.go:171","msg":"trace[946785670] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"462.59112ms","start":"2026-07-24T05:13:01.792888Z","end":"2026-07-24T05:13:02.255479Z","steps":["trace[946785670] 'process raft request' (duration: 461.402555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:02.255677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:01.792881Z","time spent":"462.699473ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:13:03.164796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.323517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:13:03.164952Z","caller":"traceutil/trace.go:171","msg":"trace[109622791] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"896.584447ms","start":"2026-07-24T05:13:02.268342Z","end":"2026-07-24T05:13:03.164926Z","steps":["trace[109622791] 'process raft request' (duration: 532.006254ms)","trace[109622791] 'compare' (duration: 363.956275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:03.16503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:02.268317Z","time spent":"896.670539ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:03.377269Z","caller":"traceutil/trace.go:171","msg":"trace[317310210] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"994.715677ms","start":"2026-07-24T05:13:02.382495Z","end":"2026-07-24T05:13:03.37721Z","steps":["trace[317310210] 'process raft request' (duration: 994.598714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.377451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:02.382454Z","time spent":"994.910052ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:03.3788Z","caller":"traceutil/trace.go:171","msg":"trace[1542327708] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"498.010751ms","start":"2026-07-24T05:13:02.880727Z","end":"2026-07-24T05:13:03.378737Z","steps":["trace[1542327708] 'read index received' (duration: 487.878236ms)","trace[1542327708] 'applied index is now lower than readState.Index' (duration: 10.131005ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:03.379366Z","caller":"traceutil/trace.go:171","msg":"trace[1019564285] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"522.603585ms","start":"2026-07-24T05:13:02.856735Z","end":"2026-07-24T05:13:03.379338Z","steps":["trace[1019564285] 'process raft request' (duration: 521.371349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.379495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:02.856697Z","time spent":"522.712098ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:03.379744Z","caller":"traceutil/trace.go:171","msg":"trace[938667718] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"190.623067ms","start":"2026-07-24T05:13:03.189097Z","end":"2026-07-24T05:13:03.37972Z","steps":["trace[938667718] 'process raft request' (duration: 189.199636ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:03.379858Z","caller":"traceutil/trace.go:171","msg":"trace[914008281] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.098098828s","start":"2026-07-24T05:13:02.281662Z","end":"2026-07-24T05:13:03.37976Z","steps":["trace[914008281] 'process raft request' (duration: 1.086912422s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.380066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.706314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T05:13:03.380122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:02.281647Z","time spent":"1.098361045s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:03.380205Z","caller":"traceutil/trace.go:171","msg":"trace[1163063713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"255.931311ms","start":"2026-07-24T05:13:03.124234Z","end":"2026-07-24T05:13:03.380165Z","steps":["trace[1163063713] 'agreement among raft nodes before linearized reading' (duration: 255.56801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.380966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.90514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:13:03.381071Z","caller":"traceutil/trace.go:171","msg":"trace[978314786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"255.995723ms","start":"2026-07-24T05:13:03.125025Z","end":"2026-07-24T05:13:03.381021Z","steps":["trace[978314786] 'agreement among raft nodes before linearized reading' (duration: 254.818299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.379873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.120214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:03.381211Z","caller":"traceutil/trace.go:171","msg":"trace[1186261292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"500.518714ms","start":"2026-07-24T05:13:02.880655Z","end":"2026-07-24T05:13:03.381174Z","steps":["trace[1186261292] 'agreement among raft nodes before linearized reading' (duration: 499.100303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.38137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:02.880554Z","time spent":"500.796182ms","remote":"127.0.0.1:39588","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-07-24T05:13:03.381587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.742255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:13:03.381737Z","caller":"traceutil/trace.go:171","msg":"trace[155723466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"255.854099ms","start":"2026-07-24T05:13:03.125779Z","end":"2026-07-24T05:13:03.381633Z","steps":["trace[155723466] 'agreement among raft nodes before linearized reading' (duration: 254.096278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:03.381796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.849038ms","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-07-24T05:13:03.381876Z","caller":"traceutil/trace.go:171","msg":"trace[867263613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"256.981321ms","start":"2026-07-24T05:13:03.12487Z","end":"2026-07-24T05:13:03.381851Z","steps":["trace[867263613] 'agreement among raft nodes before linearized reading' (duration: 255.004024ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:07.609337Z","caller":"traceutil/trace.go:171","msg":"trace[1724026591] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"206.350789ms","start":"2026-07-24T05:13:07.40295Z","end":"2026-07-24T05:13:07.609301Z","steps":["trace[1724026591] 'read index received' (duration: 205.627429ms)","trace[1724026591] 'applied index is now lower than readState.Index' (duration: 722.05µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:07.60945Z","caller":"traceutil/trace.go:171","msg":"trace[856225937] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"209.569859ms","start":"2026-07-24T05:13:07.399798Z","end":"2026-07-24T05:13:07.609368Z","steps":["trace[856225937] 'process raft request' (duration: 208.747696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:07.609612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.631087ms","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-07-24T05:13:07.609718Z","caller":"traceutil/trace.go:171","msg":"trace[528075673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"206.784191ms","start":"2026-07-24T05:13:07.402912Z","end":"2026-07-24T05:13:07.609696Z","steps":["trace[528075673] 'agreement among raft nodes before linearized reading' (duration: 206.512773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:07.609745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.943065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hb29f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-24T05:13:07.609796Z","caller":"traceutil/trace.go:171","msg":"trace[1731861623] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hb29f; range_end:; response_count:1; response_revision:605; }","duration":"128.051148ms","start":"2026-07-24T05:13:07.481734Z","end":"2026-07-24T05:13:07.609785Z","steps":["trace[1731861623] 'agreement among raft nodes before linearized reading' (duration: 127.830832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:07.609791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.956854ms","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-07-24T05:13:07.609936Z","caller":"traceutil/trace.go:171","msg":"trace[940235698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"197.15221ms","start":"2026-07-24T05:13:07.412747Z","end":"2026-07-24T05:13:07.609899Z","steps":["trace[940235698] 'agreement among raft nodes before linearized reading' (duration: 196.773679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:07.609957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.410689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:13:07.610099Z","caller":"traceutil/trace.go:171","msg":"trace[1201067080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"196.608004ms","start":"2026-07-24T05:13:07.413461Z","end":"2026-07-24T05:13:07.610069Z","steps":["trace[1201067080] 'agreement among raft nodes before linearized reading' (duration: 196.255825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:07.610343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.158702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:13:07.610379Z","caller":"traceutil/trace.go:171","msg":"trace[1591292238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"196.215784ms","start":"2026-07-24T05:13:07.414155Z","end":"2026-07-24T05:13:07.610371Z","steps":["trace[1591292238] 'agreement among raft nodes before linearized reading' (duration: 195.36663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:07.946193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.17778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:13:07.946356Z","caller":"traceutil/trace.go:171","msg":"trace[954770196] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"330.441766ms","start":"2026-07-24T05:13:07.615893Z","end":"2026-07-24T05:13:07.946334Z","steps":["trace[954770196] 'process raft request' (duration: 138.013068ms)","trace[954770196] 'compare' (duration: 191.978965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:07.946424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.615859Z","time spent":"330.534178ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:08.249679Z","caller":"traceutil/trace.go:171","msg":"trace[1906441956] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"632.275925ms","start":"2026-07-24T05:13:07.617368Z","end":"2026-07-24T05:13:08.249644Z","steps":["trace[1906441956] 'process raft request' (duration: 632.10425ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:08.24986Z","caller":"traceutil/trace.go:171","msg":"trace[354694600] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"633.860529ms","start":"2026-07-24T05:13:07.615964Z","end":"2026-07-24T05:13:08.249825Z","steps":["trace[354694600] 'process raft request' (duration: 633.129149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:08.249907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.617357Z","time spent":"632.428789ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:13:08.249974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.615938Z","time spent":"633.985153ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:08.250427Z","caller":"traceutil/trace.go:171","msg":"trace[1246000381] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"623.624302ms","start":"2026-07-24T05:13:07.626788Z","end":"2026-07-24T05:13:08.250412Z","steps":["trace[1246000381] 'process raft request' (duration: 623.481958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:08.250509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.626764Z","time spent":"623.702493ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:08.250497Z","caller":"traceutil/trace.go:171","msg":"trace[603365588] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"632.848891ms","start":"2026-07-24T05:13:07.617576Z","end":"2026-07-24T05:13:08.250424Z","steps":["trace[603365588] 'process raft request' (duration: 632.013748ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:08.2505Z","caller":"traceutil/trace.go:171","msg":"trace[254210395] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"391.762816ms","start":"2026-07-24T05:13:07.858601Z","end":"2026-07-24T05:13:08.250364Z","steps":["trace[254210395] 'read index received' (duration: 390.687896ms)","trace[254210395] 'applied index is now lower than readState.Index' (duration: 1.07428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:08.250776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.144027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:08.251181Z","caller":"traceutil/trace.go:171","msg":"trace[1361013332] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:610; }","duration":"392.563719ms","start":"2026-07-24T05:13:07.858572Z","end":"2026-07-24T05:13:08.251136Z","steps":["trace[1361013332] 'agreement among raft nodes before linearized reading' (duration: 392.112016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:08.251269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.858545Z","time spent":"392.701372ms","remote":"127.0.0.1:39810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-07-24T05:13:08.251302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.290782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:08.251345Z","caller":"traceutil/trace.go:171","msg":"trace[593271017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"370.390085ms","start":"2026-07-24T05:13:07.88094Z","end":"2026-07-24T05:13:08.25133Z","steps":["trace[593271017] 'agreement among raft nodes before linearized reading' (duration: 370.294572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:08.251377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.88091Z","time spent":"370.460097ms","remote":"127.0.0.1:39582","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-07-24T05:13:08.250821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:07.617547Z","time spent":"633.098568ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:13:09.216653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.509124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:13:09.216818Z","caller":"traceutil/trace.go:171","msg":"trace[41237] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"888.317074ms","start":"2026-07-24T05:13:08.328471Z","end":"2026-07-24T05:13:09.216788Z","steps":["trace[41237] 'process raft request' (duration: 517.578733ms)","trace[41237] 'compare' (duration: 370.189976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:09.216882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:08.328418Z","time spent":"888.439527ms","remote":"127.0.0.1:39810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:09.275496Z","caller":"traceutil/trace.go:171","msg":"trace[1458537972] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"946.442807ms","start":"2026-07-24T05:13:08.329032Z","end":"2026-07-24T05:13:09.275475Z","steps":["trace[1458537972] 'process raft request' (duration: 944.521584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:09.275585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:08.329009Z","time spent":"946.53795ms","remote":"127.0.0.1:39686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:09.277183Z","caller":"traceutil/trace.go:171","msg":"trace[1288398170] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"395.285569ms","start":"2026-07-24T05:13:08.88188Z","end":"2026-07-24T05:13:09.277166Z","steps":["trace[1288398170] 'read index received' (duration: 391.585667ms)","trace[1288398170] 'applied index is now lower than readState.Index' (duration: 3.698402ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:13:09.277521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.643999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:09.277567Z","caller":"traceutil/trace.go:171","msg":"trace[1132403126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"395.765203ms","start":"2026-07-24T05:13:08.88179Z","end":"2026-07-24T05:13:09.277555Z","steps":["trace[1132403126] 'agreement among raft nodes before linearized reading' (duration: 395.436214ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:09.277759Z","caller":"traceutil/trace.go:171","msg":"trace[942154473] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"552.591239ms","start":"2026-07-24T05:13:08.725136Z","end":"2026-07-24T05:13:09.277727Z","steps":["trace[942154473] 'process raft request' (duration: 551.361415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:09.277823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:08.881753Z","time spent":"396.058611ms","remote":"127.0.0.1:39588","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-07-24T05:13:09.277826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:08.7251Z","time spent":"552.692722ms","remote":"127.0.0.1:39670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:11.086788Z","caller":"traceutil/trace.go:171","msg":"trace[6338758] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"388.468345ms","start":"2026-07-24T05:13:10.698214Z","end":"2026-07-24T05:13:11.086682Z","steps":["trace[6338758] 'process raft request' (duration: 387.198461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:11.087015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:10.698191Z","time spent":"388.719382ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T05:13:11.100528Z","caller":"traceutil/trace.go:171","msg":"trace[1485148257] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"219.772737ms","start":"2026-07-24T05:13:10.880711Z","end":"2026-07-24T05:13:11.100483Z","steps":["trace[1485148257] 'read index received' (duration: 204.785701ms)","trace[1485148257] 'applied index is now lower than readState.Index' (duration: 14.986136ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:11.100801Z","caller":"traceutil/trace.go:171","msg":"trace[116558448] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"306.673825ms","start":"2026-07-24T05:13:10.794093Z","end":"2026-07-24T05:13:11.100767Z","steps":["trace[116558448] 'process raft request' (duration: 306.014167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:11.100816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.091097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:11.100894Z","caller":"traceutil/trace.go:171","msg":"trace[13210428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"220.24707ms","start":"2026-07-24T05:13:10.880629Z","end":"2026-07-24T05:13:11.100876Z","steps":["trace[13210428] 'agreement among raft nodes before linearized reading' (duration: 220.110716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:11.101075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:10.79406Z","time spent":"306.88448ms","remote":"127.0.0.1:39670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:13:21.410572Z","caller":"traceutil/trace.go:171","msg":"trace[234965865] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"196.01211ms","start":"2026-07-24T05:13:21.214532Z","end":"2026-07-24T05:13:21.410544Z","steps":["trace[234965865] 'process raft request' (duration: 195.838265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.26485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.120287ms","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-07-24T05:13:25.264963Z","caller":"traceutil/trace.go:171","msg":"trace[758931273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"929.29145ms","start":"2026-07-24T05:13:24.335651Z","end":"2026-07-24T05:13:25.264943Z","steps":["trace[758931273] 'range keys from in-memory index tree' (duration: 928.919431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.264999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:24.335625Z","time spent":"929.365983ms","remote":"127.0.0.1:39856","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-07-24T05:13:25.265078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.709534ms","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-07-24T05:13:25.265119Z","caller":"traceutil/trace.go:171","msg":"trace[906727341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"925.770356ms","start":"2026-07-24T05:13:24.33934Z","end":"2026-07-24T05:13:25.26511Z","steps":["trace[906727341] 'range keys from in-memory index tree' (duration: 925.562531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.265141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:24.339328Z","time spent":"925.807616ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T05:13:25.265641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.506988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:13:25.265668Z","caller":"traceutil/trace.go:171","msg":"trace[271948040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"925.582131ms","start":"2026-07-24T05:13:24.340077Z","end":"2026-07-24T05:13:25.26566Z","steps":["trace[271948040] 'range keys from in-memory index tree' (duration: 924.972666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.265688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:24.340056Z","time spent":"925.626781ms","remote":"127.0.0.1:39856","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-07-24T05:13:25.33303Z","caller":"traceutil/trace.go:171","msg":"trace[737272403] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"451.943788ms","start":"2026-07-24T05:13:24.881052Z","end":"2026-07-24T05:13:25.332996Z","steps":["trace[737272403] 'read index received' (duration: 451.675972ms)","trace[737272403] 'applied index is now lower than readState.Index' (duration: 266.686µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:13:25.333175Z","caller":"traceutil/trace.go:171","msg":"trace[1813214038] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"710.229724ms","start":"2026-07-24T05:13:24.62293Z","end":"2026-07-24T05:13:25.333159Z","steps":["trace[1813214038] 'process raft request' (duration: 709.855565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.333346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:24.622898Z","time spent":"710.320306ms","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:13:25.333493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.42891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:25.333595Z","caller":"traceutil/trace.go:171","msg":"trace[1526826386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"452.589124ms","start":"2026-07-24T05:13:24.880991Z","end":"2026-07-24T05:13:25.33358Z","steps":["trace[1526826386] 'agreement among raft nodes before linearized reading' (duration: 452.241586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.333692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:13:24.880961Z","time spent":"452.720957ms","remote":"127.0.0.1:39588","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-07-24T05:13:25.375256Z","caller":"traceutil/trace.go:171","msg":"trace[1921866458] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"105.747929ms","start":"2026-07-24T05:13:25.269487Z","end":"2026-07-24T05:13:25.375235Z","steps":["trace[1921866458] 'process raft request' (duration: 105.518714ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:25.375368Z","caller":"traceutil/trace.go:171","msg":"trace[1155450042] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"105.186756ms","start":"2026-07-24T05:13:25.270153Z","end":"2026-07-24T05:13:25.375339Z","steps":["trace[1155450042] 'process raft request' (duration: 105.015352ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:13:25.375512Z","caller":"traceutil/trace.go:171","msg":"trace[1571759295] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"105.105633ms","start":"2026-07-24T05:13:25.270375Z","end":"2026-07-24T05:13:25.375481Z","steps":["trace[1571759295] 'process raft request' (duration: 104.876338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:13:25.375497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.004968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:13:25.375588Z","caller":"traceutil/trace.go:171","msg":"trace[1175087335] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:787; }","duration":"209.168062ms","start":"2026-07-24T05:13:25.166398Z","end":"2026-07-24T05:13:25.375566Z","steps":["trace[1175087335] 'agreement among raft nodes before linearized reading' (duration: 208.963667ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.289728Z","caller":"traceutil/trace.go:171","msg":"trace[1124917315] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"373.243206ms","start":"2026-07-24T05:15:59.916443Z","end":"2026-07-24T05:16:00.289687Z","steps":["trace[1124917315] 'process raft request' (duration: 373.015813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:00.289945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:15:59.916423Z","time spent":"373.417528ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:00.440419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-24T05:16:00.4406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.853541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T05:16:00.44047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.384411ms","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-07-24T05:16:00.440656Z","caller":"traceutil/trace.go:171","msg":"trace[1011004092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1465; }","duration":"124.942611ms","start":"2026-07-24T05:16:00.31569Z","end":"2026-07-24T05:16:00.440633Z","steps":["trace[1011004092] 'range keys from in-memory index tree' (duration: 124.726899ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.440752Z","caller":"traceutil/trace.go:171","msg":"trace[629521710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1465; }","duration":"126.672314ms","start":"2026-07-24T05:16:00.314022Z","end":"2026-07-24T05:16:00.440694Z","steps":["trace[629521710] 'range keys from in-memory index tree' (duration: 126.160208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:00.440542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.508653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:16:00.440769Z","caller":"traceutil/trace.go:171","msg":"trace[2128975533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1465; }","duration":"119.589843ms","start":"2026-07-24T05:16:00.320987Z","end":"2026-07-24T05:16:00.440576Z","steps":["trace[2128975533] 'range keys from in-memory index tree' (duration: 119.106556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:00.440535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.593817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T05:16:00.440965Z","caller":"traceutil/trace.go:171","msg":"trace[1051420550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1465; }","duration":"116.966668ms","start":"2026-07-24T05:16:00.323973Z","end":"2026-07-24T05:16:00.440939Z","steps":["trace[1051420550] 'range keys from in-memory index tree' (duration: 116.333801ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.441008Z","caller":"traceutil/trace.go:171","msg":"trace[832098857] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:1465; }","duration":"108.093883ms","start":"2026-07-24T05:16:00.332903Z","end":"2026-07-24T05:16:00.440996Z","steps":["trace[832098857] 'range keys from in-memory index tree' (duration: 107.448785ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.618596Z","caller":"traceutil/trace.go:171","msg":"trace[1967501958] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"172.291516ms","start":"2026-07-24T05:16:00.446276Z","end":"2026-07-24T05:16:00.618567Z","steps":["trace[1967501958] 'process raft request' (duration: 172.260906ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.618891Z","caller":"traceutil/trace.go:171","msg":"trace[212306729] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"173.793176ms","start":"2026-07-24T05:16:00.445088Z","end":"2026-07-24T05:16:00.618881Z","steps":["trace[212306729] 'process raft request' (duration: 173.398481ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.619036Z","caller":"traceutil/trace.go:171","msg":"trace[1161496983] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"173.36234ms","start":"2026-07-24T05:16:00.445664Z","end":"2026-07-24T05:16:00.619026Z","steps":["trace[1161496983] 'process raft request' (duration: 172.851073ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:00.61888Z","caller":"traceutil/trace.go:171","msg":"trace[2131327117] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"173.984538ms","start":"2026-07-24T05:16:00.444876Z","end":"2026-07-24T05:16:00.618861Z","steps":["trace[2131327117] 'process raft request' (duration: 173.458551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:01.856731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.351171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:01.856865Z","caller":"traceutil/trace.go:171","msg":"trace[1132813971] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1541; }","duration":"562.978597ms","start":"2026-07-24T05:16:01.293865Z","end":"2026-07-24T05:16:01.856843Z","steps":["trace[1132813971] 'read index received' (duration: 101.335252ms)","trace[1132813971] 'applied index is now lower than readState.Index' (duration: 461.642095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:01.857023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.147009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:01.857058Z","caller":"traceutil/trace.go:171","msg":"trace[1154422909] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1474; }","duration":"563.22729ms","start":"2026-07-24T05:16:01.29382Z","end":"2026-07-24T05:16:01.857047Z","steps":["trace[1154422909] 'agreement among raft nodes before linearized reading' (duration: 563.079888ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:01.857527Z","caller":"traceutil/trace.go:171","msg":"trace[1795071768] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"643.597902ms","start":"2026-07-24T05:16:01.213902Z","end":"2026-07-24T05:16:01.8575Z","steps":["trace[1795071768] 'process raft request' (duration: 181.37619ms)","trace[1795071768] 'compare' (duration: 461.201949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:01.857636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:01.213864Z","time spent":"643.729463ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T05:16:01.85761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:01.293795Z","time spent":"563.795597ms","remote":"127.0.0.1:39834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:16:03.123383Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119931649757310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:16:03.610819Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143046269s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T05:16:04.041012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.800947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:04.041122Z","caller":"traceutil/trace.go:171","msg":"trace[53017700] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"1.418124483s","start":"2026-07-24T05:16:02.622978Z","end":"2026-07-24T05:16:04.041103Z","steps":["trace[53017700] 'read index received' (duration: 988.000872ms)","trace[53017700] 'applied index is now lower than readState.Index' (duration: 430.122361ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:04.041217Z","caller":"traceutil/trace.go:171","msg":"trace[1807728070] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"1.573601466s","start":"2026-07-24T05:16:02.467603Z","end":"2026-07-24T05:16:04.041205Z","steps":["trace[1807728070] 'process raft request' (duration: 1.143533316s)","trace[1807728070] 'compare' (duration: 429.434732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:04.041269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.46757Z","time spent":"1.573670887s","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:04.0415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418536839s","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-07-24T05:16:04.041528Z","caller":"traceutil/trace.go:171","msg":"trace[895981624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1475; }","duration":"1.418616299s","start":"2026-07-24T05:16:02.622902Z","end":"2026-07-24T05:16:04.041518Z","steps":["trace[895981624] 'agreement among raft nodes before linearized reading' (duration: 1.418490768s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.041557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.622874Z","time spent":"1.418676421s","remote":"127.0.0.1:39856","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-07-24T05:16:04.041765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418491868s","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-07-24T05:16:04.041791Z","caller":"traceutil/trace.go:171","msg":"trace[320967512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1475; }","duration":"1.418533509s","start":"2026-07-24T05:16:02.62325Z","end":"2026-07-24T05:16:04.041783Z","steps":["trace[320967512] 'agreement among raft nodes before linearized reading' (duration: 1.418462698s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.041823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.623242Z","time spent":"1.418575089s","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T05:16:04.042003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.857516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:16:04.04203Z","caller":"traceutil/trace.go:171","msg":"trace[1098109174] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1475; }","duration":"418.938597ms","start":"2026-07-24T05:16:03.623083Z","end":"2026-07-24T05:16:04.042021Z","steps":["trace[1098109174] 'agreement among raft nodes before linearized reading' (duration: 418.892706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.042059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:03.623058Z","time spent":"418.991917ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:16:04.042241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418463308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:16:04.042384Z","caller":"traceutil/trace.go:171","msg":"trace[352298158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1475; }","duration":"1.41863543s","start":"2026-07-24T05:16:02.623728Z","end":"2026-07-24T05:16:04.042363Z","steps":["trace[352298158] 'agreement among raft nodes before linearized reading' (duration: 1.418307766s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.042628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.623721Z","time spent":"1.418890363s","remote":"127.0.0.1:39856","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-07-24T05:16:04.042657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419038736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T05:16:04.042741Z","caller":"traceutil/trace.go:171","msg":"trace[657427594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1475; }","duration":"1.419214828s","start":"2026-07-24T05:16:02.623505Z","end":"2026-07-24T05:16:04.04272Z","steps":["trace[657427594] 'agreement among raft nodes before linearized reading' (duration: 1.418707541s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.04279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.623478Z","time spent":"1.419303409s","remote":"127.0.0.1:39856","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-07-24T05:16:04.042902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158105307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:16:04.042933Z","caller":"traceutil/trace.go:171","msg":"trace[1331888699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1475; }","duration":"1.158160878s","start":"2026-07-24T05:16:02.884762Z","end":"2026-07-24T05:16:04.042923Z","steps":["trace[1331888699] 'agreement among raft nodes before linearized reading' (duration: 1.158072606s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.042964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.884751Z","time spent":"1.158207378s","remote":"127.0.0.1:39856","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-07-24T05:16:04.043123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201072484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:16:04.043204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162312191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:04.043236Z","caller":"traceutil/trace.go:171","msg":"trace[537890514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1475; }","duration":"1.162394702s","start":"2026-07-24T05:16:02.880831Z","end":"2026-07-24T05:16:04.043226Z","steps":["trace[537890514] 'agreement among raft nodes before linearized reading' (duration: 1.162348282s)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:04.043227Z","caller":"traceutil/trace.go:171","msg":"trace[2063060153] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1475; }","duration":"1.201182725s","start":"2026-07-24T05:16:02.842006Z","end":"2026-07-24T05:16:04.043189Z","steps":["trace[2063060153] 'agreement among raft nodes before linearized reading' (duration: 1.201006083s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:04.043266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.880804Z","time spent":"1.162456153s","remote":"127.0.0.1:39588","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-07-24T05:16:04.043289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:02.841936Z","time spent":"1.201342017s","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:16:04.746822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.512359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:04.746991Z","caller":"traceutil/trace.go:171","msg":"trace[863764200] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"699.88764ms","start":"2026-07-24T05:16:04.047075Z","end":"2026-07-24T05:16:04.746963Z","steps":["trace[863764200] 'process raft request' (duration: 499.119827ms)","trace[863764200] 'compare' (duration: 200.359707ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:04.74707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:04.047049Z","time spent":"699.982841ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:05.892478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.34607256s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T05:16:05.955122Z","caller":"traceutil/trace.go:171","msg":"trace[1183801013] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"1.907538971s","start":"2026-07-24T05:16:04.047512Z","end":"2026-07-24T05:16:05.955051Z","steps":["trace[1183801013] 'process raft request' (duration: 1.845191466s)","trace[1183801013] 'compare' (duration: 60.47648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:05.955351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:04.047486Z","time spent":"1.907737263s","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:05.955399Z","caller":"traceutil/trace.go:171","msg":"trace[294419928] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1543; }","duration":"1.904483452s","start":"2026-07-24T05:16:04.050885Z","end":"2026-07-24T05:16:05.955368Z","steps":["trace[294419928] 'read index received' (duration: 495.330078ms)","trace[294419928] 'applied index is now lower than readState.Index' (duration: 1.409151794s)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:05.955513Z","caller":"traceutil/trace.go:171","msg":"trace[2095098831] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"1.907061834s","start":"2026-07-24T05:16:04.048439Z","end":"2026-07-24T05:16:05.955501Z","steps":["trace[2095098831] 'process raft request' (duration: 1.906769161s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:05.955586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:04.048426Z","time spent":"1.907125105s","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:05.955638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904719905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:05.95568Z","caller":"traceutil/trace.go:171","msg":"trace[1948156037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1480; }","duration":"1.904818856s","start":"2026-07-24T05:16:04.050852Z","end":"2026-07-24T05:16:05.955671Z","steps":["trace[1948156037] 'agreement among raft nodes before linearized reading' (duration: 1.904688934s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:05.955716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:04.05084Z","time spent":"1.904867996s","remote":"127.0.0.1:39588","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-07-24T05:16:05.955519Z","caller":"traceutil/trace.go:171","msg":"trace[243206895] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"1.907043734s","start":"2026-07-24T05:16:04.048464Z","end":"2026-07-24T05:16:05.955507Z","steps":["trace[243206895] 'process raft request' (duration: 1.906855702s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:05.955813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:04.048452Z","time spent":"1.907329128s","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:05.955791Z","caller":"traceutil/trace.go:171","msg":"trace[1714510809] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"1.907736543s","start":"2026-07-24T05:16:04.048018Z","end":"2026-07-24T05:16:05.955754Z","steps":["trace[1714510809] 'process raft request' (duration: 1.906049091s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:05.956016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:04.047995Z","time spent":"1.907887015s","remote":"127.0.0.1:39856","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-07-24T05:16:05.990551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.888107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T05:16:05.99059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.694077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:16:05.990663Z","caller":"traceutil/trace.go:171","msg":"trace[840259550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1480; }","duration":"172.07258ms","start":"2026-07-24T05:16:05.818566Z","end":"2026-07-24T05:16:05.990639Z","steps":["trace[840259550] 'agreement among raft nodes before linearized reading' (duration: 171.874817ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:05.990668Z","caller":"traceutil/trace.go:171","msg":"trace[1281402763] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1480; }","duration":"813.827448ms","start":"2026-07-24T05:16:05.176822Z","end":"2026-07-24T05:16:05.990649Z","steps":["trace[1281402763] 'agreement among raft nodes before linearized reading' (duration: 813.658876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:05.990823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:05.1768Z","time spent":"813.9611ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:16:06.449071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.329968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:06.449194Z","caller":"traceutil/trace.go:171","msg":"trace[1556214455] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1481; }","duration":"251.544771ms","start":"2026-07-24T05:16:06.197623Z","end":"2026-07-24T05:16:06.449168Z","steps":["trace[1556214455] 'range keys from in-memory index tree' (duration: 251.198216ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:08.066842Z","caller":"traceutil/trace.go:171","msg":"trace[246232408] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"102.269888ms","start":"2026-07-24T05:16:07.964544Z","end":"2026-07-24T05:16:08.066814Z","steps":["trace[246232408] 'process raft request' (duration: 101.955037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:08.388169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.294656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:08.38849Z","caller":"traceutil/trace.go:171","msg":"trace[306147125] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"421.895015ms","start":"2026-07-24T05:16:07.966575Z","end":"2026-07-24T05:16:08.38847Z","steps":["trace[306147125] 'process raft request' (duration: 421.848594ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:08.388502Z","caller":"traceutil/trace.go:171","msg":"trace[1487910488] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"422.965319ms","start":"2026-07-24T05:16:07.965498Z","end":"2026-07-24T05:16:08.388464Z","steps":["trace[1487910488] 'process raft request' (duration: 191.266279ms)","trace[1487910488] 'compare' (duration: 231.057098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:08.388578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:07.966561Z","time spent":"421.976588ms","remote":"127.0.0.1:39856","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-07-24T05:16:08.388657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:07.965476Z","time spent":"423.090683ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:08.388953Z","caller":"traceutil/trace.go:171","msg":"trace[698331801] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"423.136312ms","start":"2026-07-24T05:16:07.965806Z","end":"2026-07-24T05:16:08.388942Z","steps":["trace[698331801] 'process raft request' (duration: 422.50667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:08.389019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:07.965797Z","time spent":"423.191833ms","remote":"127.0.0.1:39856","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-07-24T05:16:08.432389Z","caller":"traceutil/trace.go:171","msg":"trace[1343060086] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1557; }","duration":"298.278873ms","start":"2026-07-24T05:16:08.134075Z","end":"2026-07-24T05:16:08.432354Z","steps":["trace[1343060086] 'read index received' (duration: 22.636383ms)","trace[1343060086] 'applied index is now lower than readState.Index' (duration: 275.64074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:08.432599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.489551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:08.432648Z","caller":"traceutil/trace.go:171","msg":"trace[1481843032] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1492; }","duration":"298.619766ms","start":"2026-07-24T05:16:08.13401Z","end":"2026-07-24T05:16:08.43263Z","steps":["trace[1481843032] 'agreement among raft nodes before linearized reading' (duration: 298.492151ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:08.432739Z","caller":"traceutil/trace.go:171","msg":"trace[1609741073] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"338.501253ms","start":"2026-07-24T05:16:08.094197Z","end":"2026-07-24T05:16:08.432698Z","steps":["trace[1609741073] 'process raft request' (duration: 338.025997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:08.432964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:08.09415Z","time spent":"338.69029ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:10.348854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.289113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"info","ts":"2026-07-24T05:16:10.348949Z","caller":"traceutil/trace.go:171","msg":"trace[1162469567] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1494; }","duration":"172.417377ms","start":"2026-07-24T05:16:10.176512Z","end":"2026-07-24T05:16:10.348929Z","steps":["trace[1162469567] 'range keys from in-memory index tree' (duration: 172.17962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:10.58964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.817364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:10.58978Z","caller":"traceutil/trace.go:171","msg":"trace[272019855] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"161.088324ms","start":"2026-07-24T05:16:10.428672Z","end":"2026-07-24T05:16:10.58976Z","steps":["trace[272019855] 'process raft request' (duration: 34.053212ms)","trace[272019855] 'compare' (duration: 126.655719ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:10.671801Z","caller":"traceutil/trace.go:171","msg":"trace[1064619239] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"242.873374ms","start":"2026-07-24T05:16:10.4289Z","end":"2026-07-24T05:16:10.671773Z","steps":["trace[1064619239] 'process raft request' (duration: 242.594474ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:10.671807Z","caller":"traceutil/trace.go:171","msg":"trace[896242048] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"242.74961ms","start":"2026-07-24T05:16:10.429021Z","end":"2026-07-24T05:16:10.671771Z","steps":["trace[896242048] 'process raft request' (duration: 242.654167ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:10.671932Z","caller":"traceutil/trace.go:171","msg":"trace[1288551254] linearizableReadLoop","detail":"{readStateIndex:1568; appliedIndex:1565; }","duration":"242.817533ms","start":"2026-07-24T05:16:10.429084Z","end":"2026-07-24T05:16:10.671901Z","steps":["trace[1288551254] 'read index received' (duration: 33.654529ms)","trace[1288551254] 'applied index is now lower than readState.Index' (duration: 209.160084ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:10.672043Z","caller":"traceutil/trace.go:171","msg":"trace[1484790143] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"233.105316ms","start":"2026-07-24T05:16:10.438908Z","end":"2026-07-24T05:16:10.672013Z","steps":["trace[1484790143] 'process raft request' (duration: 232.814035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:10.672121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.003689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T05:16:10.672162Z","caller":"traceutil/trace.go:171","msg":"trace[1737306207] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1499; }","duration":"243.084972ms","start":"2026-07-24T05:16:10.429066Z","end":"2026-07-24T05:16:10.672151Z","steps":["trace[1737306207] 'agreement among raft nodes before linearized reading' (duration: 242.965338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:10.672169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.980154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-07-24T05:16:10.672206Z","caller":"traceutil/trace.go:171","msg":"trace[1156189610] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1499; }","duration":"221.060837ms","start":"2026-07-24T05:16:10.451133Z","end":"2026-07-24T05:16:10.672194Z","steps":["trace[1156189610] 'agreement among raft nodes before linearized reading' (duration: 220.982164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:10.67235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.285463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-07-24T05:16:10.672397Z","caller":"traceutil/trace.go:171","msg":"trace[1952274881] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:1499; }","duration":"193.369055ms","start":"2026-07-24T05:16:10.479013Z","end":"2026-07-24T05:16:10.672382Z","steps":["trace[1952274881] 'agreement among raft nodes before linearized reading' (duration: 193.273212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:11.091357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.862744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-07-24T05:16:11.091453Z","caller":"traceutil/trace.go:171","msg":"trace[1151530557] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1500; }","duration":"155.046691ms","start":"2026-07-24T05:16:10.936384Z","end":"2026-07-24T05:16:11.091431Z","steps":["trace[1151530557] 'range keys from in-memory index tree' (duration: 154.655247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:11.09152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.578649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-07-24T05:16:11.091644Z","caller":"traceutil/trace.go:171","msg":"trace[2081438265] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1500; }","duration":"153.756066ms","start":"2026-07-24T05:16:10.93786Z","end":"2026-07-24T05:16:11.091616Z","steps":["trace[2081438265] 'range keys from in-memory index tree' (duration: 153.486206ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:11.663523Z","caller":"traceutil/trace.go:171","msg":"trace[164828802] linearizableReadLoop","detail":"{readStateIndex:1571; appliedIndex:1570; }","duration":"102.507724ms","start":"2026-07-24T05:16:11.560965Z","end":"2026-07-24T05:16:11.663473Z","steps":["trace[164828802] 'read index received' (duration: 102.231874ms)","trace[164828802] 'applied index is now lower than readState.Index' (duration: 274.42µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:11.663662Z","caller":"traceutil/trace.go:171","msg":"trace[286430916] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"127.28809ms","start":"2026-07-24T05:16:11.536327Z","end":"2026-07-24T05:16:11.663615Z","steps":["trace[286430916] 'process raft request' (duration: 126.888636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:11.663773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.771993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-07-24T05:16:11.663816Z","caller":"traceutil/trace.go:171","msg":"trace[582884107] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1501; }","duration":"102.911047ms","start":"2026-07-24T05:16:11.560889Z","end":"2026-07-24T05:16:11.6638Z","steps":["trace[582884107] 'agreement among raft nodes before linearized reading' (duration: 102.786193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:11.901724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.148047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-07-24T05:16:11.901835Z","caller":"traceutil/trace.go:171","msg":"trace[1912636592] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1501; }","duration":"158.289822ms","start":"2026-07-24T05:16:11.743512Z","end":"2026-07-24T05:16:11.901802Z","steps":["trace[1912636592] 'range keys from in-memory index tree' (duration: 158.031473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:11.901876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.086019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-07-24T05:16:11.901971Z","caller":"traceutil/trace.go:171","msg":"trace[500547204] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1501; }","duration":"148.211364ms","start":"2026-07-24T05:16:11.753732Z","end":"2026-07-24T05:16:11.901943Z","steps":["trace[500547204] 'range keys from in-memory index tree' (duration: 147.957965ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:13.088146Z","caller":"traceutil/trace.go:171","msg":"trace[1137477166] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"409.628944ms","start":"2026-07-24T05:16:12.678492Z","end":"2026-07-24T05:16:13.088121Z","steps":["trace[1137477166] 'process raft request' (duration: 409.482879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:13.088308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:12.678475Z","time spent":"409.750987ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:13.384939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.797191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:13.385225Z","caller":"traceutil/trace.go:171","msg":"trace[1254084031] linearizableReadLoop","detail":"{readStateIndex:1576; appliedIndex:1574; }","duration":"631.692669ms","start":"2026-07-24T05:16:12.75351Z","end":"2026-07-24T05:16:13.385203Z","steps":["trace[1254084031] 'read index received' (duration: 334.642693ms)","trace[1254084031] 'applied index is now lower than readState.Index' (duration: 297.048776ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:13.385259Z","caller":"traceutil/trace.go:171","msg":"trace[1452026245] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"703.711148ms","start":"2026-07-24T05:16:12.681508Z","end":"2026-07-24T05:16:13.385219Z","steps":["trace[1452026245] 'process raft request' (duration: 703.565253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:13.385357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.830824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:841"} {"level":"warn","ts":"2026-07-24T05:16:13.385382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.090803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-07-24T05:16:13.385404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.848067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:16:13.385423Z","caller":"traceutil/trace.go:171","msg":"trace[458249130] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1506; }","duration":"623.143894ms","start":"2026-07-24T05:16:12.762268Z","end":"2026-07-24T05:16:13.385412Z","steps":["trace[458249130] 'agreement among raft nodes before linearized reading' (duration: 623.059721ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:13.385429Z","caller":"traceutil/trace.go:171","msg":"trace[910100323] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1506; }","duration":"631.962268ms","start":"2026-07-24T05:16:12.753447Z","end":"2026-07-24T05:16:13.385409Z","steps":["trace[910100323] 'agreement among raft nodes before linearized reading' (duration: 631.813503ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:13.385448Z","caller":"traceutil/trace.go:171","msg":"trace[1524598561] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1506; }","duration":"305.936431ms","start":"2026-07-24T05:16:13.079501Z","end":"2026-07-24T05:16:13.385438Z","steps":["trace[1524598561] 'agreement among raft nodes before linearized reading' (duration: 305.845918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:13.385441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:12.681484Z","time spent":"703.855913ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:13.385462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:12.762262Z","time spent":"623.190317ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":782,"request content":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-07-24T05:16:13.385473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:12.753419Z","time spent":"632.044421ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":864,"request content":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" "} {"level":"warn","ts":"2026-07-24T05:16:13.385484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:13.079486Z","time spent":"305.989212ms","remote":"127.0.0.1:39686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:16:13.385662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.109246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T05:16:13.385766Z","caller":"traceutil/trace.go:171","msg":"trace[2030360016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1506; }","duration":"474.293872ms","start":"2026-07-24T05:16:12.911444Z","end":"2026-07-24T05:16:13.385737Z","steps":["trace[2030360016] 'agreement among raft nodes before linearized reading' (duration: 473.900329ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:13.385297Z","caller":"traceutil/trace.go:171","msg":"trace[1776678761] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"704.309978ms","start":"2026-07-24T05:16:12.680928Z","end":"2026-07-24T05:16:13.385238Z","steps":["trace[1776678761] 'process raft request' (duration: 540.090302ms)","trace[1776678761] 'compare' (duration: 163.556413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:13.385838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:12.911411Z","time spent":"474.407186ms","remote":"127.0.0.1:39856","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-07-24T05:16:13.385948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:12.680918Z","time spent":"704.932549ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:13.654209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.379173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:13.654352Z","caller":"traceutil/trace.go:171","msg":"trace[1850904074] linearizableReadLoop","detail":"{readStateIndex:1577; appliedIndex:1576; }","duration":"177.571884ms","start":"2026-07-24T05:16:13.47673Z","end":"2026-07-24T05:16:13.654301Z","steps":["trace[1850904074] 'read index received' (duration: 54.942243ms)","trace[1850904074] 'applied index is now lower than readState.Index' (duration: 122.62791ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:13.654391Z","caller":"traceutil/trace.go:171","msg":"trace[2130276683] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"261.421766ms","start":"2026-07-24T05:16:13.39294Z","end":"2026-07-24T05:16:13.654362Z","steps":["trace[2130276683] 'process raft request' (duration: 138.763384ms)","trace[2130276683] 'compare' (duration: 122.128083ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:13.654601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.871913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:880"} {"level":"info","ts":"2026-07-24T05:16:13.654643Z","caller":"traceutil/trace.go:171","msg":"trace[1039045016] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:1507; }","duration":"177.959617ms","start":"2026-07-24T05:16:13.476671Z","end":"2026-07-24T05:16:13.654631Z","steps":["trace[1039045016] 'agreement among raft nodes before linearized reading' (duration: 177.848463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:13.654779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.330371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-07-24T05:16:13.654903Z","caller":"traceutil/trace.go:171","msg":"trace[1100846289] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1507; }","duration":"176.505356ms","start":"2026-07-24T05:16:13.478376Z","end":"2026-07-24T05:16:13.654881Z","steps":["trace[1100846289] 'agreement among raft nodes before linearized reading' (duration: 176.214106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:13.655017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.570172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:13.655133Z","caller":"traceutil/trace.go:171","msg":"trace[1342282596] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1507; }","duration":"118.853001ms","start":"2026-07-24T05:16:13.53625Z","end":"2026-07-24T05:16:13.655103Z","steps":["trace[1342282596] 'agreement among raft nodes before linearized reading' (duration: 118.377445ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:14.770786Z","caller":"traceutil/trace.go:171","msg":"trace[713634108] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"102.072411ms","start":"2026-07-24T05:16:14.668666Z","end":"2026-07-24T05:16:14.770738Z","steps":["trace[713634108] 'process raft request' (duration: 101.75645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:14.967026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.466767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-07-24T05:16:14.967128Z","caller":"traceutil/trace.go:171","msg":"trace[1322744921] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1509; }","duration":"100.608321ms","start":"2026-07-24T05:16:14.866493Z","end":"2026-07-24T05:16:14.967101Z","steps":["trace[1322744921] 'range keys from in-memory index tree' (duration: 100.355292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:15.596446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.335333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"warn","ts":"2026-07-24T05:16:15.596563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.559861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-07-24T05:16:15.596626Z","caller":"traceutil/trace.go:171","msg":"trace[956798] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1512; }","duration":"145.550921ms","start":"2026-07-24T05:16:15.451044Z","end":"2026-07-24T05:16:15.596595Z","steps":["trace[956798] 'range keys from in-memory index tree' (duration: 145.21897ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:15.596663Z","caller":"traceutil/trace.go:171","msg":"trace[1208739748] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1512; }","duration":"145.704986ms","start":"2026-07-24T05:16:15.450923Z","end":"2026-07-24T05:16:15.596628Z","steps":["trace[1208739748] 'range keys from in-memory index tree' (duration: 145.380636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:16.350985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.870307ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T05:16:16.351213Z","caller":"traceutil/trace.go:171","msg":"trace[198905920] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1583; }","duration":"301.540482ms","start":"2026-07-24T05:16:16.049648Z","end":"2026-07-24T05:16:16.351188Z","steps":["trace[198905920] 'read index received' (duration: 19.417836ms)","trace[198905920] 'applied index is now lower than readState.Index' (duration: 282.120345ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:16.351489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.826841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:1 size:595"} {"level":"info","ts":"2026-07-24T05:16:16.35154Z","caller":"traceutil/trace.go:171","msg":"trace[948841191] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:1; response_revision:1513; }","duration":"301.909944ms","start":"2026-07-24T05:16:16.049616Z","end":"2026-07-24T05:16:16.351526Z","steps":["trace[948841191] 'agreement among raft nodes before linearized reading' (duration: 301.777989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:16.351582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:16.049606Z","time spent":"301.966806ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":618,"request content":"key:\"/registry/clusterroles/system:kube-aggregator\" "} {"level":"warn","ts":"2026-07-24T05:16:16.351461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.276271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" ","response":"range_response_count:1 size:1039"} {"level":"info","ts":"2026-07-24T05:16:16.351802Z","caller":"traceutil/trace.go:171","msg":"trace[2032797570] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:1; response_revision:1513; }","duration":"277.675495ms","start":"2026-07-24T05:16:16.074104Z","end":"2026-07-24T05:16:16.35178Z","steps":["trace[2032797570] 'agreement among raft nodes before linearized reading' (duration: 277.261901ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:16.912623Z","caller":"traceutil/trace.go:171","msg":"trace[309684577] linearizableReadLoop","detail":"{readStateIndex:1586; appliedIndex:1585; }","duration":"121.974102ms","start":"2026-07-24T05:16:16.790621Z","end":"2026-07-24T05:16:16.912595Z","steps":["trace[309684577] 'read index received' (duration: 121.763075ms)","trace[309684577] 'applied index is now lower than readState.Index' (duration: 209.587µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:16.912742Z","caller":"traceutil/trace.go:171","msg":"trace[2117422449] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"134.079041ms","start":"2026-07-24T05:16:16.778626Z","end":"2026-07-24T05:16:16.912705Z","steps":["trace[2117422449] 'process raft request' (duration: 133.75498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:16.912868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.21336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-24T05:16:16.912964Z","caller":"traceutil/trace.go:171","msg":"trace[238479525] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1515; }","duration":"122.347255ms","start":"2026-07-24T05:16:16.790595Z","end":"2026-07-24T05:16:16.912942Z","steps":["trace[238479525] 'agreement among raft nodes before linearized reading' (duration: 122.128188ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:17.625066Z","caller":"traceutil/trace.go:171","msg":"trace[1781135854] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"201.091166ms","start":"2026-07-24T05:16:17.423933Z","end":"2026-07-24T05:16:17.625024Z","steps":["trace[1781135854] 'process raft request' (duration: 200.937281ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:17.62513Z","caller":"traceutil/trace.go:171","msg":"trace[1255654259] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1588; }","duration":"190.980136ms","start":"2026-07-24T05:16:17.434107Z","end":"2026-07-24T05:16:17.625087Z","steps":["trace[1255654259] 'read index received' (duration: 190.959345ms)","trace[1255654259] 'applied index is now lower than readState.Index' (duration: 17.381µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:17.625327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.200783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"info","ts":"2026-07-24T05:16:17.625436Z","caller":"traceutil/trace.go:171","msg":"trace[2082897783] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:1517; }","duration":"191.333438ms","start":"2026-07-24T05:16:17.434069Z","end":"2026-07-24T05:16:17.625403Z","steps":["trace[2082897783] 'agreement among raft nodes before linearized reading' (duration: 191.133401ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:17.78052Z","caller":"traceutil/trace.go:171","msg":"trace[819568559] linearizableReadLoop","detail":"{readStateIndex:1589; appliedIndex:1588; }","duration":"155.273945ms","start":"2026-07-24T05:16:17.625215Z","end":"2026-07-24T05:16:17.780489Z","steps":["trace[819568559] 'read index received' (duration: 72.902523ms)","trace[819568559] 'applied index is now lower than readState.Index' (duration: 82.370202ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:17.780657Z","caller":"traceutil/trace.go:171","msg":"trace[547057125] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"286.332424ms","start":"2026-07-24T05:16:17.494288Z","end":"2026-07-24T05:16:17.780621Z","steps":["trace[547057125] 'process raft request' (duration: 203.956493ms)","trace[547057125] 'compare' (duration: 82.102822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:17.780795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.28262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-07-24T05:16:17.780893Z","caller":"traceutil/trace.go:171","msg":"trace[1919376126] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1518; }","duration":"343.429746ms","start":"2026-07-24T05:16:17.43744Z","end":"2026-07-24T05:16:17.78087Z","steps":["trace[1919376126] 'agreement among raft nodes before linearized reading' (duration: 343.226279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:17.780937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:17.437413Z","time spent":"343.515079ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":636,"request content":"key:\"/registry/clusterroles/system:public-info-viewer\" "} {"level":"warn","ts":"2026-07-24T05:16:17.7811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.377242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T05:16:17.781192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.436994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:16:17.781209Z","caller":"traceutil/trace.go:171","msg":"trace[1752116619] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1518; }","duration":"337.521627ms","start":"2026-07-24T05:16:17.443655Z","end":"2026-07-24T05:16:17.781177Z","steps":["trace[1752116619] 'agreement among raft nodes before linearized reading' (duration: 337.31112ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:17.781224Z","caller":"traceutil/trace.go:171","msg":"trace[127785853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1518; }","duration":"329.91113ms","start":"2026-07-24T05:16:17.451303Z","end":"2026-07-24T05:16:17.781215Z","steps":["trace[127785853] 'agreement among raft nodes before linearized reading' (duration: 329.353961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:17.78125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:17.451277Z","time spent":"329.964892ms","remote":"127.0.0.1:39856","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-07-24T05:16:17.781261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:17.443645Z","time spent":"337.604919ms","remote":"127.0.0.1:39776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-07-24T05:16:17.927006Z","caller":"traceutil/trace.go:171","msg":"trace[472049653] linearizableReadLoop","detail":"{readStateIndex:1591; appliedIndex:1589; }","duration":"140.417225ms","start":"2026-07-24T05:16:17.786566Z","end":"2026-07-24T05:16:17.926983Z","steps":["trace[472049653] 'read index received' (duration: 74.355892ms)","trace[472049653] 'applied index is now lower than readState.Index' (duration: 66.060133ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:17.92718Z","caller":"traceutil/trace.go:171","msg":"trace[1753244705] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"141.352116ms","start":"2026-07-24T05:16:17.785758Z","end":"2026-07-24T05:16:17.92711Z","steps":["trace[1753244705] 'process raft request' (duration: 120.07075ms)","trace[1753244705] 'compare' (duration: 21.036518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:17.927288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.677803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"info","ts":"2026-07-24T05:16:17.927409Z","caller":"traceutil/trace.go:171","msg":"trace[442815144] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:1520; }","duration":"140.836869ms","start":"2026-07-24T05:16:17.786547Z","end":"2026-07-24T05:16:17.927384Z","steps":["trace[442815144] 'agreement among raft nodes before linearized reading' (duration: 140.55155ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:18.536598Z","caller":"traceutil/trace.go:171","msg":"trace[1317517535] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"121.364285ms","start":"2026-07-24T05:16:18.415202Z","end":"2026-07-24T05:16:18.536566Z","steps":["trace[1317517535] 'process raft request' (duration: 121.089486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:18.536725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.500737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-07-24T05:16:18.537006Z","caller":"traceutil/trace.go:171","msg":"trace[1434057389] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1521; }","duration":"103.855069ms","start":"2026-07-24T05:16:18.433135Z","end":"2026-07-24T05:16:18.53699Z","steps":["trace[1434057389] 'agreement among raft nodes before linearized reading' (duration: 103.500917ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:18.536582Z","caller":"traceutil/trace.go:171","msg":"trace[450468665] linearizableReadLoop","detail":"{readStateIndex:1592; appliedIndex:1591; }","duration":"103.315231ms","start":"2026-07-24T05:16:18.433203Z","end":"2026-07-24T05:16:18.536519Z","steps":["trace[450468665] 'read index received' (duration: 103.040822ms)","trace[450468665] 'applied index is now lower than readState.Index' (duration: 272.939µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:19.24447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.539597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:19.244585Z","caller":"traceutil/trace.go:171","msg":"trace[1670457816] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1522; }","duration":"155.717543ms","start":"2026-07-24T05:16:19.088846Z","end":"2026-07-24T05:16:19.244564Z","steps":["trace[1670457816] 'count revisions from in-memory index tree' (duration: 155.450084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:19.244491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.7281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-07-24T05:16:19.244712Z","caller":"traceutil/trace.go:171","msg":"trace[1511813030] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1522; }","duration":"158.0142ms","start":"2026-07-24T05:16:19.086673Z","end":"2026-07-24T05:16:19.244687Z","steps":["trace[1511813030] 'range keys from in-memory index tree' (duration: 157.612427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:19.245304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.799398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-07-24T05:16:19.245361Z","caller":"traceutil/trace.go:171","msg":"trace[223734239] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:1522; }","duration":"153.87057ms","start":"2026-07-24T05:16:19.091474Z","end":"2026-07-24T05:16:19.245344Z","steps":["trace[223734239] 'range keys from in-memory index tree' (duration: 152.808086ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:19.742614Z","caller":"traceutil/trace.go:171","msg":"trace[1938303116] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"109.250844ms","start":"2026-07-24T05:16:19.633325Z","end":"2026-07-24T05:16:19.742576Z","steps":["trace[1938303116] 'process raft request' (duration: 108.968514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:19.925991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.085804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-07-24T05:16:19.926088Z","caller":"traceutil/trace.go:171","msg":"trace[1359615401] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1524; }","duration":"102.25ms","start":"2026-07-24T05:16:19.823816Z","end":"2026-07-24T05:16:19.926066Z","steps":["trace[1359615401] 'range keys from in-memory index tree' (duration: 101.964031ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:20.081619Z","caller":"traceutil/trace.go:171","msg":"trace[408066944] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"149.734242ms","start":"2026-07-24T05:16:19.931855Z","end":"2026-07-24T05:16:20.081589Z","steps":["trace[408066944] 'process raft request' (duration: 149.542165ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:20.152529Z","caller":"traceutil/trace.go:171","msg":"trace[1114597398] linearizableReadLoop","detail":"{readStateIndex:1597; appliedIndex:1595; }","duration":"144.928822ms","start":"2026-07-24T05:16:20.007572Z","end":"2026-07-24T05:16:20.1525Z","steps":["trace[1114597398] 'read index received' (duration: 73.819131ms)","trace[1114597398] 'applied index is now lower than readState.Index' (duration: 71.108101ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:20.152673Z","caller":"traceutil/trace.go:171","msg":"trace[1849234924] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"216.55273ms","start":"2026-07-24T05:16:19.936074Z","end":"2026-07-24T05:16:20.152626Z","steps":["trace[1849234924] 'process raft request' (duration: 202.568846ms)","trace[1849234924] 'compare' (duration: 13.738006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:20.152732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.143279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-07-24T05:16:20.152777Z","caller":"traceutil/trace.go:171","msg":"trace[553227584] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1526; }","duration":"145.247113ms","start":"2026-07-24T05:16:20.007515Z","end":"2026-07-24T05:16:20.152763Z","steps":["trace[553227584] 'agreement among raft nodes before linearized reading' (duration: 145.148109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.152923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.051034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-07-24T05:16:20.153034Z","caller":"traceutil/trace.go:171","msg":"trace[425891482] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1526; }","duration":"135.21682ms","start":"2026-07-24T05:16:20.017795Z","end":"2026-07-24T05:16:20.153012Z","steps":["trace[425891482] 'agreement among raft nodes before linearized reading' (duration: 134.981722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.521863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.659616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-07-24T05:16:20.521977Z","caller":"traceutil/trace.go:171","msg":"trace[1157423916] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1526; }","duration":"192.811222ms","start":"2026-07-24T05:16:20.32914Z","end":"2026-07-24T05:16:20.521951Z","steps":["trace[1157423916] 'range keys from in-memory index tree' (duration: 192.553263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.522228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.542349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T05:16:20.522325Z","caller":"traceutil/trace.go:171","msg":"trace[996246488] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:1526; }","duration":"188.676324ms","start":"2026-07-24T05:16:20.333618Z","end":"2026-07-24T05:16:20.522294Z","steps":["trace[996246488] 'range keys from in-memory index tree' (duration: 188.408616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.522274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.220985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-07-24T05:16:20.522619Z","caller":"traceutil/trace.go:171","msg":"trace[141580845] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1526; }","duration":"196.612638ms","start":"2026-07-24T05:16:20.32599Z","end":"2026-07-24T05:16:20.522603Z","steps":["trace[141580845] 'range keys from in-memory index tree' (duration: 196.119771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.88338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:20.523928Z","time spent":"359.444384ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-24T05:16:20.883636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.924611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-07-24T05:16:20.883791Z","caller":"traceutil/trace.go:171","msg":"trace[1299376569] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1526; }","duration":"357.188709ms","start":"2026-07-24T05:16:20.526579Z","end":"2026-07-24T05:16:20.883768Z","steps":["trace[1299376569] 'agreement among raft nodes before linearized reading' (duration: 356.886339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.883885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:20.526551Z","time spent":"357.320173ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":770,"request content":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" "} {"level":"info","ts":"2026-07-24T05:16:20.884017Z","caller":"traceutil/trace.go:171","msg":"trace[1883856086] linearizableReadLoop","detail":"{readStateIndex:1598; appliedIndex:1597; }","duration":"356.639851ms","start":"2026-07-24T05:16:20.526653Z","end":"2026-07-24T05:16:20.883293Z","steps":["trace[1883856086] 'read index received' (duration: 356.439384ms)","trace[1883856086] 'applied index is now lower than readState.Index' (duration: 198.537µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:20.884293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.846538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-07-24T05:16:20.884397Z","caller":"traceutil/trace.go:171","msg":"trace[1774209477] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1526; }","duration":"356.988262ms","start":"2026-07-24T05:16:20.527397Z","end":"2026-07-24T05:16:20.884385Z","steps":["trace[1774209477] 'agreement among raft nodes before linearized reading' (duration: 356.815227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.884481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:20.527379Z","time spent":"357.084926ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":782,"request content":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" "} {"level":"warn","ts":"2026-07-24T05:16:20.884495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.574407ms","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-07-24T05:16:20.884756Z","caller":"traceutil/trace.go:171","msg":"trace[777370093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1526; }","duration":"343.844396ms","start":"2026-07-24T05:16:20.540856Z","end":"2026-07-24T05:16:20.8847Z","steps":["trace[777370093] 'agreement among raft nodes before linearized reading' (duration: 343.402841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:20.88483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:20.540835Z","time spent":"343.96838ms","remote":"127.0.0.1:39856","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-07-24T05:16:21.007186Z","caller":"traceutil/trace.go:171","msg":"trace[1546049450] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"122.121454ms","start":"2026-07-24T05:16:20.885026Z","end":"2026-07-24T05:16:21.007148Z","steps":["trace[1546049450] 'process raft request' (duration: 92.742079ms)","trace[1546049450] 'compare' (duration: 29.23944ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:21.047265Z","caller":"traceutil/trace.go:171","msg":"trace[1411659298] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"154.378019ms","start":"2026-07-24T05:16:20.892863Z","end":"2026-07-24T05:16:21.047241Z","steps":["trace[1411659298] 'process raft request' (duration: 153.620204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:21.257121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.637906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:21.257243Z","caller":"traceutil/trace.go:171","msg":"trace[1304026800] linearizableReadLoop","detail":"{readStateIndex:1601; appliedIndex:1600; }","duration":"123.251234ms","start":"2026-07-24T05:16:21.133973Z","end":"2026-07-24T05:16:21.257224Z","steps":["trace[1304026800] 'read index received' (duration: 46.191µs)","trace[1304026800] 'applied index is now lower than readState.Index' (duration: 123.203943ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:21.257296Z","caller":"traceutil/trace.go:171","msg":"trace[1800056711] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"204.781889ms","start":"2026-07-24T05:16:21.0525Z","end":"2026-07-24T05:16:21.257282Z","steps":["trace[1800056711] 'process raft request' (duration: 64.860994ms)","trace[1800056711] 'compare' (duration: 139.506491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:21.257344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.362507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:deployment-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-07-24T05:16:21.25741Z","caller":"traceutil/trace.go:171","msg":"trace[904637254] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1529; }","duration":"123.448911ms","start":"2026-07-24T05:16:21.133918Z","end":"2026-07-24T05:16:21.257367Z","steps":["trace[904637254] 'agreement among raft nodes before linearized reading' (duration: 123.374828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:21.269417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.782916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-07-24T05:16:21.269453Z","caller":"traceutil/trace.go:171","msg":"trace[214477823] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1530; }","duration":"134.874808ms","start":"2026-07-24T05:16:21.13457Z","end":"2026-07-24T05:16:21.269445Z","steps":["trace[214477823] 'agreement among raft nodes before linearized reading' (duration: 134.791725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:21.942148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.526048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-07-24T05:16:21.942283Z","caller":"traceutil/trace.go:171","msg":"trace[487898987] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1531; }","duration":"102.684004ms","start":"2026-07-24T05:16:21.839574Z","end":"2026-07-24T05:16:21.942258Z","steps":["trace[487898987] 'range keys from in-memory index tree' (duration: 102.344052ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:22.302014Z","caller":"traceutil/trace.go:171","msg":"trace[868438709] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"386.300796ms","start":"2026-07-24T05:16:21.915675Z","end":"2026-07-24T05:16:22.301976Z","steps":["trace[868438709] 'process raft request' (duration: 385.972625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.302216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.742925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:22.302058Z","caller":"traceutil/trace.go:171","msg":"trace[240583871] linearizableReadLoop","detail":"{readStateIndex:1604; appliedIndex:1603; }","duration":"358.383293ms","start":"2026-07-24T05:16:21.943438Z","end":"2026-07-24T05:16:22.301822Z","steps":["trace[240583871] 'read index received' (duration: 358.064502ms)","trace[240583871] 'applied index is now lower than readState.Index' (duration: 317.711µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:22.302418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:21.915648Z","time spent":"386.456851ms","remote":"127.0.0.1:39856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:22.302377Z","caller":"traceutil/trace.go:171","msg":"trace[2075821759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1532; }","duration":"358.935712ms","start":"2026-07-24T05:16:21.943369Z","end":"2026-07-24T05:16:22.302305Z","steps":["trace[2075821759] 'agreement among raft nodes before linearized reading' (duration: 358.723235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.302577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:21.94334Z","time spent":"359.225781ms","remote":"127.0.0.1:39582","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-07-24T05:16:22.303088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.300074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-07-24T05:16:22.303307Z","caller":"traceutil/trace.go:171","msg":"trace[1775481535] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1532; }","duration":"356.520811ms","start":"2026-07-24T05:16:21.946741Z","end":"2026-07-24T05:16:22.303262Z","steps":["trace[1775481535] 'agreement among raft nodes before linearized reading' (duration: 356.259742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.303356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.529906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T05:16:22.303571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:21.946723Z","time spent":"356.830692ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":766,"request content":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" "} {"level":"warn","ts":"2026-07-24T05:16:22.303934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.499544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T05:16:22.30355Z","caller":"traceutil/trace.go:171","msg":"trace[916312703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1532; }","duration":"216.754773ms","start":"2026-07-24T05:16:22.086743Z","end":"2026-07-24T05:16:22.303498Z","steps":["trace[916312703] 'agreement among raft nodes before linearized reading' (duration: 216.295948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.303643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.330042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-07-24T05:16:22.304212Z","caller":"traceutil/trace.go:171","msg":"trace[1476302389] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1532; }","duration":"355.906471ms","start":"2026-07-24T05:16:21.948281Z","end":"2026-07-24T05:16:22.304187Z","steps":["trace[1476302389] 'agreement among raft nodes before linearized reading' (duration: 355.27473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.304388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:21.948271Z","time spent":"356.027425ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":798,"request content":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" "} {"level":"info","ts":"2026-07-24T05:16:22.304061Z","caller":"traceutil/trace.go:171","msg":"trace[880066412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1532; }","duration":"147.654439ms","start":"2026-07-24T05:16:22.156371Z","end":"2026-07-24T05:16:22.304025Z","steps":["trace[880066412] 'agreement among raft nodes before linearized reading' (duration: 147.004898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.843837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.735047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:22.844172Z","caller":"traceutil/trace.go:171","msg":"trace[1176575414] linearizableReadLoop","detail":"{readStateIndex:1606; appliedIndex:1604; }","duration":"451.745755ms","start":"2026-07-24T05:16:22.3924Z","end":"2026-07-24T05:16:22.844146Z","steps":["trace[1176575414] 'read index received' (duration: 50.563724ms)","trace[1176575414] 'applied index is now lower than readState.Index' (duration: 401.180751ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:22.844202Z","caller":"traceutil/trace.go:171","msg":"trace[2012287773] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"533.384822ms","start":"2026-07-24T05:16:22.31079Z","end":"2026-07-24T05:16:22.844175Z","steps":["trace[2012287773] 'process raft request' (duration: 533.198896ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:22.844204Z","caller":"traceutil/trace.go:171","msg":"trace[1880914810] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"535.392238ms","start":"2026-07-24T05:16:22.308778Z","end":"2026-07-24T05:16:22.84417Z","steps":["trace[1880914810] 'process raft request' (duration: 134.174635ms)","trace[1880914810] 'compare' (duration: 400.478699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:22.844329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:22.310766Z","time spent":"533.500916ms","remote":"127.0.0.1:39856","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-07-24T05:16:22.84433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.916221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"warn","ts":"2026-07-24T05:16:22.844364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.506285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:731"} {"level":"warn","ts":"2026-07-24T05:16:22.844349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:22.308753Z","time spent":"535.513522ms","remote":"127.0.0.1:39856","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-07-24T05:16:22.84442Z","caller":"traceutil/trace.go:171","msg":"trace[1255691283] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:1534; }","duration":"443.576577ms","start":"2026-07-24T05:16:22.400825Z","end":"2026-07-24T05:16:22.844401Z","steps":["trace[1255691283] 'agreement among raft nodes before linearized reading' (duration: 443.466083ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:22.844417Z","caller":"traceutil/trace.go:171","msg":"trace[414225499] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1534; }","duration":"452.054266ms","start":"2026-07-24T05:16:22.392342Z","end":"2026-07-24T05:16:22.844396Z","steps":["trace[414225499] 'agreement among raft nodes before linearized reading' (duration: 451.913051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.844433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.325068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T05:16:22.844473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:22.392318Z","time spent":"452.147029ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":798,"request content":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-07-24T05:16:22.844483Z","caller":"traceutil/trace.go:171","msg":"trace[2067442565] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1534; }","duration":"278.421132ms","start":"2026-07-24T05:16:22.566048Z","end":"2026-07-24T05:16:22.844469Z","steps":["trace[2067442565] 'agreement among raft nodes before linearized reading' (duration: 278.321069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:22.844471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:22.400816Z","time spent":"443.644529ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":754,"request content":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" "} {"level":"warn","ts":"2026-07-24T05:16:23.175394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.604937ms","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-07-24T05:16:23.175502Z","caller":"traceutil/trace.go:171","msg":"trace[1684653104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1534; }","duration":"122.795593ms","start":"2026-07-24T05:16:23.052685Z","end":"2026-07-24T05:16:23.175481Z","steps":["trace[1684653104] 'range keys from in-memory index tree' (duration: 122.37506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:23.175595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.639232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-07-24T05:16:23.175734Z","caller":"traceutil/trace.go:171","msg":"trace[163552283] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:1534; }","duration":"229.956143ms","start":"2026-07-24T05:16:22.945748Z","end":"2026-07-24T05:16:23.175704Z","steps":["trace[163552283] 'range keys from in-memory index tree' (duration: 229.498188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:23.175725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.293624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-07-24T05:16:23.175978Z","caller":"traceutil/trace.go:171","msg":"trace[1217778075] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1534; }","duration":"157.443838ms","start":"2026-07-24T05:16:23.018376Z","end":"2026-07-24T05:16:23.17582Z","steps":["trace[1217778075] 'range keys from in-memory index tree' (duration: 157.200461ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:23.340881Z","caller":"traceutil/trace.go:171","msg":"trace[613262270] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"160.432697ms","start":"2026-07-24T05:16:23.180417Z","end":"2026-07-24T05:16:23.340849Z","steps":["trace[613262270] 'process raft request' (duration: 160.24445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:23.544839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.884126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T05:16:23.545094Z","caller":"traceutil/trace.go:171","msg":"trace[1150791173] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"198.433032ms","start":"2026-07-24T05:16:23.346636Z","end":"2026-07-24T05:16:23.545069Z","steps":["trace[1150791173] 'process raft request' (duration: 198.338449ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:23.545219Z","caller":"traceutil/trace.go:171","msg":"trace[54591101] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"271.683892ms","start":"2026-07-24T05:16:23.273495Z","end":"2026-07-24T05:16:23.545179Z","steps":["trace[54591101] 'process raft request' (duration: 170.343451ms)","trace[54591101] 'compare' (duration: 100.684249ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:23.545247Z","caller":"traceutil/trace.go:171","msg":"trace[1872108203] linearizableReadLoop","detail":"{readStateIndex:1608; appliedIndex:1607; }","duration":"204.351276ms","start":"2026-07-24T05:16:23.340859Z","end":"2026-07-24T05:16:23.545211Z","steps":["trace[1872108203] 'read index received' (duration: 102.923922ms)","trace[1872108203] 'applied index is now lower than readState.Index' (duration: 101.424364ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:23.545326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.569322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-07-24T05:16:23.545357Z","caller":"traceutil/trace.go:171","msg":"trace[70490154] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1537; }","duration":"276.650444ms","start":"2026-07-24T05:16:23.268696Z","end":"2026-07-24T05:16:23.545346Z","steps":["trace[70490154] 'agreement among raft nodes before linearized reading' (duration: 276.566422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:23.545463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.604124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"warn","ts":"2026-07-24T05:16:23.545464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.243374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:16:23.545509Z","caller":"traceutil/trace.go:171","msg":"trace[1357922960] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:1537; }","duration":"280.678906ms","start":"2026-07-24T05:16:23.264817Z","end":"2026-07-24T05:16:23.545496Z","steps":["trace[1357922960] 'agreement among raft nodes before linearized reading' (duration: 280.556102ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:23.545513Z","caller":"traceutil/trace.go:171","msg":"trace[917842647] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1537; }","duration":"125.341967ms","start":"2026-07-24T05:16:23.420159Z","end":"2026-07-24T05:16:23.545501Z","steps":["trace[917842647] 'agreement among raft nodes before linearized reading' (duration: 125.235774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:23.818533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.026867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T05:16:23.818698Z","caller":"traceutil/trace.go:171","msg":"trace[51536012] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1537; }","duration":"118.270295ms","start":"2026-07-24T05:16:23.700407Z","end":"2026-07-24T05:16:23.818677Z","steps":["trace[51536012] 'count revisions from in-memory index tree' (duration: 117.906553ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:23.966244Z","caller":"traceutil/trace.go:171","msg":"trace[1313759889] linearizableReadLoop","detail":"{readStateIndex:1610; appliedIndex:1609; }","duration":"142.358764ms","start":"2026-07-24T05:16:23.823849Z","end":"2026-07-24T05:16:23.966208Z","steps":["trace[1313759889] 'read index received' (duration: 142.063905ms)","trace[1313759889] 'applied index is now lower than readState.Index' (duration: 293.779µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:23.966351Z","caller":"traceutil/trace.go:171","msg":"trace[972809468] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"142.604663ms","start":"2026-07-24T05:16:23.82372Z","end":"2026-07-24T05:16:23.966324Z","steps":["trace[972809468] 'process raft request' (duration: 142.298493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:23.966546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.666435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-07-24T05:16:23.966642Z","caller":"traceutil/trace.go:171","msg":"trace[1842700481] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1538; }","duration":"142.83058ms","start":"2026-07-24T05:16:23.823789Z","end":"2026-07-24T05:16:23.966619Z","steps":["trace[1842700481] 'agreement among raft nodes before linearized reading' (duration: 142.606042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:24.172971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.102084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"warn","ts":"2026-07-24T05:16:24.173034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.440039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-07-24T05:16:24.173106Z","caller":"traceutil/trace.go:171","msg":"trace[1278094844] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1538; }","duration":"111.329442ms","start":"2026-07-24T05:16:24.061746Z","end":"2026-07-24T05:16:24.173075Z","steps":["trace[1278094844] 'range keys from in-memory index tree' (duration: 110.910148ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:24.173137Z","caller":"traceutil/trace.go:171","msg":"trace[715636164] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1538; }","duration":"124.602675ms","start":"2026-07-24T05:16:24.048509Z","end":"2026-07-24T05:16:24.173111Z","steps":["trace[715636164] 'range keys from in-memory index tree' (duration: 124.313476ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:25.717984Z","caller":"traceutil/trace.go:171","msg":"trace[2011942018] linearizableReadLoop","detail":"{readStateIndex:1616; appliedIndex:1615; }","duration":"112.862066ms","start":"2026-07-24T05:16:25.605086Z","end":"2026-07-24T05:16:25.717948Z","steps":["trace[2011942018] 'read index received' (duration: 37.487178ms)","trace[2011942018] 'applied index is now lower than readState.Index' (duration: 75.373678ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:25.718025Z","caller":"traceutil/trace.go:171","msg":"trace[558677444] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"128.190813ms","start":"2026-07-24T05:16:25.589795Z","end":"2026-07-24T05:16:25.717986Z","steps":["trace[558677444] 'process raft request' (duration: 52.753663ms)","trace[558677444] 'compare' (duration: 75.241354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:25.718237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.115744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:25.718281Z","caller":"traceutil/trace.go:171","msg":"trace[1764190747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1543; }","duration":"113.239178ms","start":"2026-07-24T05:16:25.605027Z","end":"2026-07-24T05:16:25.718266Z","steps":["trace[1764190747] 'agreement among raft nodes before linearized reading' (duration: 113.127035ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:26.142184Z","caller":"traceutil/trace.go:171","msg":"trace[1279654945] linearizableReadLoop","detail":"{readStateIndex:1617; appliedIndex:1616; }","duration":"163.098247ms","start":"2026-07-24T05:16:25.979054Z","end":"2026-07-24T05:16:26.142152Z","steps":["trace[1279654945] 'read index received' (duration: 162.763146ms)","trace[1279654945] 'applied index is now lower than readState.Index' (duration: 333.811µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:26.142339Z","caller":"traceutil/trace.go:171","msg":"trace[1111552320] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"165.389983ms","start":"2026-07-24T05:16:25.976863Z","end":"2026-07-24T05:16:26.142253Z","steps":["trace[1111552320] 'process raft request' (duration: 165.092793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:26.14243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.147024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-07-24T05:16:26.142479Z","caller":"traceutil/trace.go:171","msg":"trace[1734662868] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:1544; }","duration":"157.243777ms","start":"2026-07-24T05:16:25.985221Z","end":"2026-07-24T05:16:26.142465Z","steps":["trace[1734662868] 'agreement among raft nodes before linearized reading' (duration: 157.133954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:26.142634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.348006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-07-24T05:16:26.142742Z","caller":"traceutil/trace.go:171","msg":"trace[164187648] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:1544; }","duration":"163.721938ms","start":"2026-07-24T05:16:25.978995Z","end":"2026-07-24T05:16:26.142717Z","steps":["trace[164187648] 'agreement among raft nodes before linearized reading' (duration: 163.269293ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:26.984221Z","caller":"traceutil/trace.go:171","msg":"trace[1763289670] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"110.195764ms","start":"2026-07-24T05:16:26.873995Z","end":"2026-07-24T05:16:26.984191Z","steps":["trace[1763289670] 'process raft request' (duration: 109.942516ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:27.303446Z","caller":"traceutil/trace.go:171","msg":"trace[731360070] linearizableReadLoop","detail":"{readStateIndex:1620; appliedIndex:1619; }","duration":"145.444894ms","start":"2026-07-24T05:16:27.157972Z","end":"2026-07-24T05:16:27.303417Z","steps":["trace[731360070] 'read index received' (duration: 145.198856ms)","trace[731360070] 'applied index is now lower than readState.Index' (duration: 245.138µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:27.30355Z","caller":"traceutil/trace.go:171","msg":"trace[626941960] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"146.383884ms","start":"2026-07-24T05:16:27.157156Z","end":"2026-07-24T05:16:27.30354Z","steps":["trace[626941960] 'process raft request' (duration: 146.081364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:27.303717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.723783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:16:27.303748Z","caller":"traceutil/trace.go:171","msg":"trace[1396824730] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1547; }","duration":"145.800635ms","start":"2026-07-24T05:16:27.157939Z","end":"2026-07-24T05:16:27.303739Z","steps":["trace[1396824730] 'agreement among raft nodes before linearized reading' (duration: 145.722103ms)"],"step_count":1}