{"level":"warn","ts":"2026-05-13T00:56:04.551047Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-13T00:56:04.551116Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-05-13T00:56:04.551167Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-13T00:56:04.551179Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-13T00:56:04.551221Z","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-05-13T00:56:04.551903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-05-13T00:56:04.551992Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-05-13T00:56:04.755781Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"192.853008ms"} {"level":"info","ts":"2026-05-13T00:56:05.035935Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-05-13T00:56:05.036057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-05-13T00:56:05.036098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-05-13T00:56:05.036118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-13T00:56:05.036134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-05-13T00:56:05.036185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-05-13T00:56:05.077451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-13T00:56:05.079602Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-13T00:56:05.082948Z","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-05-13T00:56:05.085235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-13T00:56:05.085437Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-13T00:56:05.085531Z","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-05-13T00:56:05.085604Z","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-05-13T00:56:05.085634Z","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-05-13T00:56:05.088316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-05-13T00:56:05.08847Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-13T00:56:05.091075Z","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-05-13T00:56:05.09126Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-13T00:56:05.091315Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-13T00:56:05.091539Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-13T00:56:05.091605Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-13T00:56:05.73662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-05-13T00:56:05.736683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-13T00:56:05.736723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-05-13T00:56:05.736752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-05-13T00:56:05.736768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-13T00:56:05.736783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-05-13T00:56:05.736799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-13T00:56:05.73775Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-05-13T00:56:05.737863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T00:56:05.737958Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T00:56:05.737889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-13T00:56:05.738106Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-13T00:56:05.738144Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-13T00:56:05.739004Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T00:56:05.739817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T00:56:05.739861Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-13T00:56:05.740976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-13T00:56:05.741478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"warn","ts":"2026-05-13T00:56:08.845249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.961754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:08.845423Z","caller":"traceutil/trace.go:171","msg":"trace[395881757] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:0; response_revision:193; }","duration":"286.190056ms","start":"2026-05-13T00:56:08.559205Z","end":"2026-05-13T00:56:08.845396Z","steps":["trace[395881757] 'range keys from in-memory index tree' (duration: 285.819683ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:09.10801Z","caller":"traceutil/trace.go:171","msg":"trace[596711457] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"259.395225ms","start":"2026-05-13T00:56:08.848568Z","end":"2026-05-13T00:56:09.107963Z","steps":["trace[596711457] 'process raft request' (duration: 259.149744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:09.726102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722302928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:56:10.226944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722302928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:56:10.429017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.31428191s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-13T00:56:10.634628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.767653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-13T00:56:10.634751Z","caller":"traceutil/trace.go:171","msg":"trace[928872919] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"1.520105061s","start":"2026-05-13T00:56:09.114625Z","end":"2026-05-13T00:56:10.63473Z","steps":["trace[928872919] 'process raft request' (duration: 1.314759503s)","trace[928872919] 'compare' (duration: 204.596791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:10.635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:09.114608Z","time spent":"1.520178652s","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T00:56:11.22556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999974273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-13T00:56:11.225665Z","caller":"traceutil/trace.go:171","msg":"trace[1850756776] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000157008s","start":"2026-05-13T00:56:09.225479Z","end":"2026-05-13T00:56:11.225636Z","steps":["trace[1850756776] 'agreement among raft nodes before linearized reading' (duration: 2.000009632s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.225714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:09.225459Z","time spent":"2.00024507s","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T00:56:11.415729Z","caller":"traceutil/trace.go:171","msg":"trace[1163294827] linearizableReadLoop","detail":"{readStateIndex:201; appliedIndex:199; }","duration":"2.190157989s","start":"2026-05-13T00:56:09.225535Z","end":"2026-05-13T00:56:11.415693Z","steps":["trace[1163294827] 'read index received' (duration: 1.203922065s)","trace[1163294827] 'applied index is now lower than readState.Index' (duration: 986.234934ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:11.415825Z","caller":"traceutil/trace.go:171","msg":"trace[833633303] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"1.773853567s","start":"2026-05-13T00:56:09.641915Z","end":"2026-05-13T00:56:11.415769Z","steps":["trace[833633303] 'process raft request' (duration: 1.773647599s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.415959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785020755s","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-05-13T00:56:11.415966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.009647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:11.415981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:09.641884Z","time spent":"1.774030354s","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:11.416021Z","caller":"traceutil/trace.go:171","msg":"trace[1327861606] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:0; response_revision:196; }","duration":"778.106321ms","start":"2026-05-13T00:56:10.637899Z","end":"2026-05-13T00:56:11.416005Z","steps":["trace[1327861606] 'agreement among raft nodes before linearized reading' (duration: 777.980116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.416045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237311648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:11.416055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:10.637874Z","time spent":"778.174162ms","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" "} {"level":"info","ts":"2026-05-13T00:56:11.416124Z","caller":"traceutil/trace.go:171","msg":"trace[577213394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:196; }","duration":"1.23738306s","start":"2026-05-13T00:56:10.178681Z","end":"2026-05-13T00:56:11.416064Z","steps":["trace[577213394] 'agreement among raft nodes before linearized reading' (duration: 1.237335828s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.416156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.979921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:11.416169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:10.17866Z","time spent":"1.237500873s","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-13T00:56:11.4162Z","caller":"traceutil/trace.go:171","msg":"trace[633885812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:196; }","duration":"143.041722ms","start":"2026-05-13T00:56:11.273145Z","end":"2026-05-13T00:56:11.416187Z","steps":["trace[633885812] 'agreement among raft nodes before linearized reading' (duration: 142.97732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.41618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.602999ms","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-05-13T00:56:11.416244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.512649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:11.416297Z","caller":"traceutil/trace.go:171","msg":"trace[1142414751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:196; }","duration":"938.794494ms","start":"2026-05-13T00:56:10.477466Z","end":"2026-05-13T00:56:11.416261Z","steps":["trace[1142414751] 'agreement among raft nodes before linearized reading' (duration: 938.585596ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:11.416316Z","caller":"traceutil/trace.go:171","msg":"trace[1742780947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:196; }","duration":"190.617263ms","start":"2026-05-13T00:56:11.225674Z","end":"2026-05-13T00:56:11.416292Z","steps":["trace[1742780947] 'agreement among raft nodes before linearized reading' (duration: 190.507209ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:11.416328Z","caller":"traceutil/trace.go:171","msg":"trace[1449527025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:196; }","duration":"1.785439359s","start":"2026-05-13T00:56:09.630868Z","end":"2026-05-13T00:56:11.416307Z","steps":["trace[1449527025] 'agreement among raft nodes before linearized reading' (duration: 1.784978162s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.416353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:10.477442Z","time spent":"938.901437ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-13T00:56:11.416382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:09.630846Z","time spent":"1.785525481s","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-13T00:56:11.416542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03708932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:11.416638Z","caller":"traceutil/trace.go:171","msg":"trace[2127228505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:196; }","duration":"1.037157771s","start":"2026-05-13T00:56:10.379416Z","end":"2026-05-13T00:56:11.416574Z","steps":["trace[2127228505] 'agreement among raft nodes before linearized reading' (duration: 1.03657507s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:11.416673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:10.379402Z","time spent":"1.037264545s","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T00:56:15.106192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.881364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T00:56:15.106307Z","caller":"traceutil/trace.go:171","msg":"trace[1207727866] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"431.8345ms","start":"2026-05-13T00:56:14.674452Z","end":"2026-05-13T00:56:15.106286Z","steps":["trace[1207727866] 'read index received' (duration: 94.559932ms)","trace[1207727866] 'applied index is now lower than readState.Index' (duration: 337.272998ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:15.106462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.015057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-13T00:56:15.10644Z","caller":"traceutil/trace.go:171","msg":"trace[726039226] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"472.158518ms","start":"2026-05-13T00:56:14.634247Z","end":"2026-05-13T00:56:15.106405Z","steps":["trace[726039226] 'process raft request' (duration: 134.814528ms)","trace[726039226] 'compare' (duration: 336.75142ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:15.106507Z","caller":"traceutil/trace.go:171","msg":"trace[1375478563] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:323; }","duration":"432.08717ms","start":"2026-05-13T00:56:14.674409Z","end":"2026-05-13T00:56:15.106496Z","steps":["trace[1375478563] 'agreement among raft nodes before linearized reading' (duration: 432.006477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:15.106547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:14.67439Z","time spent":"432.149592ms","remote":"127.0.0.1:43344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-05-13T00:56:15.106601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:14.634228Z","time spent":"472.288682ms","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T00:56:15.106736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.841828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-13T00:56:15.10678Z","caller":"traceutil/trace.go:171","msg":"trace[562375906] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:323; }","duration":"381.884539ms","start":"2026-05-13T00:56:14.724884Z","end":"2026-05-13T00:56:15.106768Z","steps":["trace[562375906] 'agreement among raft nodes before linearized reading' (duration: 381.793036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:15.106808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:14.724814Z","time spent":"381.986832ms","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-13T00:56:15.390343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.90261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T00:56:15.390457Z","caller":"traceutil/trace.go:171","msg":"trace[477141811] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"235.547961ms","start":"2026-05-13T00:56:15.154889Z","end":"2026-05-13T00:56:15.390437Z","steps":["trace[477141811] 'read index received' (duration: 119.361001ms)","trace[477141811] 'applied index is now lower than readState.Index' (duration: 116.18585ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:15.390521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.626084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:15.390554Z","caller":"traceutil/trace.go:171","msg":"trace[1218625959] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:325; }","duration":"235.686496ms","start":"2026-05-13T00:56:15.154856Z","end":"2026-05-13T00:56:15.390542Z","steps":["trace[1218625959] 'agreement among raft nodes before linearized reading' (duration: 235.630394ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:15.39053Z","caller":"traceutil/trace.go:171","msg":"trace[1305017797] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"271.59379ms","start":"2026-05-13T00:56:15.118894Z","end":"2026-05-13T00:56:15.390488Z","steps":["trace[1305017797] 'process raft request' (duration: 155.424851ms)","trace[1305017797] 'compare' (duration: 115.759155ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:15.585532Z","caller":"traceutil/trace.go:171","msg":"trace[448684819] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"123.337143ms","start":"2026-05-13T00:56:15.462157Z","end":"2026-05-13T00:56:15.585494Z","steps":["trace[448684819] 'process raft request' (duration: 53.890161ms)","trace[448684819] 'compare' (duration: 69.220044ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:15.585719Z","caller":"traceutil/trace.go:171","msg":"trace[951963137] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"120.818065ms","start":"2026-05-13T00:56:15.464868Z","end":"2026-05-13T00:56:15.585686Z","steps":["trace[951963137] 'process raft request' (duration: 120.67349ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:15.799239Z","caller":"traceutil/trace.go:171","msg":"trace[1072799716] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"116.821592ms","start":"2026-05-13T00:56:15.682385Z","end":"2026-05-13T00:56:15.799206Z","steps":["trace[1072799716] 'read index received' (duration: 92.51661ms)","trace[1072799716] 'applied index is now lower than readState.Index' (duration: 24.303612ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:15.805244Z","caller":"traceutil/trace.go:171","msg":"trace[1667379909] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"129.345176ms","start":"2026-05-13T00:56:15.675855Z","end":"2026-05-13T00:56:15.8052Z","steps":["trace[1667379909] 'process raft request' (duration: 99.056533ms)","trace[1667379909] 'compare' (duration: 24.137476ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:15.805672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.24928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-13T00:56:15.805788Z","caller":"traceutil/trace.go:171","msg":"trace[1053917068] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:335; }","duration":"123.385504ms","start":"2026-05-13T00:56:15.68237Z","end":"2026-05-13T00:56:15.805755Z","steps":["trace[1053917068] 'agreement among raft nodes before linearized reading' (duration: 123.166767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:16.849769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.704289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-13T00:56:16.849802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.464447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-13T00:56:16.849858Z","caller":"traceutil/trace.go:171","msg":"trace[1042388883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:345; }","duration":"124.854614ms","start":"2026-05-13T00:56:16.724983Z","end":"2026-05-13T00:56:16.849838Z","steps":["trace[1042388883] 'range keys from in-memory index tree' (duration: 124.540494ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:16.849888Z","caller":"traceutil/trace.go:171","msg":"trace[2140141378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:345; }","duration":"175.605053ms","start":"2026-05-13T00:56:16.674255Z","end":"2026-05-13T00:56:16.84986Z","steps":["trace[2140141378] 'range keys from in-memory index tree' (duration: 175.329352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:16.850086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.833637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-13T00:56:16.850225Z","caller":"traceutil/trace.go:171","msg":"trace[286358096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:345; }","duration":"219.381637ms","start":"2026-05-13T00:56:16.630825Z","end":"2026-05-13T00:56:16.850207Z","steps":["trace[286358096] 'range keys from in-memory index tree' (duration: 218.724933ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:17.845737Z","caller":"traceutil/trace.go:171","msg":"trace[1752354997] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"225.897761ms","start":"2026-05-13T00:56:17.619807Z","end":"2026-05-13T00:56:17.845704Z","steps":["trace[1752354997] 'read index received' (duration: 176.533661ms)","trace[1752354997] 'applied index is now lower than readState.Index' (duration: 49.36325ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:17.845831Z","caller":"traceutil/trace.go:171","msg":"trace[1931111853] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"239.589234ms","start":"2026-05-13T00:56:17.606187Z","end":"2026-05-13T00:56:17.845777Z","steps":["trace[1931111853] 'process raft request' (duration: 190.154092ms)","trace[1931111853] 'compare' (duration: 49.251676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:17.845909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.087658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T00:56:17.845944Z","caller":"traceutil/trace.go:171","msg":"trace[296319223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"226.186771ms","start":"2026-05-13T00:56:17.619748Z","end":"2026-05-13T00:56:17.845935Z","steps":["trace[296319223] 'agreement among raft nodes before linearized reading' (duration: 226.099198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:17.845985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.016692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-13T00:56:17.846064Z","caller":"traceutil/trace.go:171","msg":"trace[1991947970] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:369; }","duration":"221.130975ms","start":"2026-05-13T00:56:17.624914Z","end":"2026-05-13T00:56:17.846045Z","steps":["trace[1991947970] 'agreement among raft nodes before linearized reading' (duration: 220.9741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:17.846133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.622946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T00:56:17.846175Z","caller":"traceutil/trace.go:171","msg":"trace[1963430538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"174.702108ms","start":"2026-05-13T00:56:17.671463Z","end":"2026-05-13T00:56:17.846165Z","steps":["trace[1963430538] 'agreement among raft nodes before linearized reading' (duration: 174.592275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:17.84624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.502796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-13T00:56:17.846287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.498206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-13T00:56:17.84632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.339651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T00:56:17.846323Z","caller":"traceutil/trace.go:171","msg":"trace[123080693] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:369; }","duration":"171.549418ms","start":"2026-05-13T00:56:17.674763Z","end":"2026-05-13T00:56:17.846313Z","steps":["trace[123080693] 'agreement among raft nodes before linearized reading' (duration: 171.494757ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:17.84633Z","caller":"traceutil/trace.go:171","msg":"trace[1200752249] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"121.62014ms","start":"2026-05-13T00:56:17.724688Z","end":"2026-05-13T00:56:17.846308Z","steps":["trace[1200752249] 'agreement among raft nodes before linearized reading' (duration: 121.474985ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:17.846349Z","caller":"traceutil/trace.go:171","msg":"trace[1004577622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"175.407702ms","start":"2026-05-13T00:56:17.670933Z","end":"2026-05-13T00:56:17.846341Z","steps":["trace[1004577622] 'agreement among raft nodes before linearized reading' (duration: 175.359071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:17.846453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.045494ms","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-05-13T00:56:17.846544Z","caller":"traceutil/trace.go:171","msg":"trace[2856059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"176.178669ms","start":"2026-05-13T00:56:17.670346Z","end":"2026-05-13T00:56:17.846524Z","steps":["trace[2856059] 'agreement among raft nodes before linearized reading' (duration: 176.004313ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:34.198916Z","caller":"traceutil/trace.go:171","msg":"trace[1671569504] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"124.852116ms","start":"2026-05-13T00:56:34.074029Z","end":"2026-05-13T00:56:34.198881Z","steps":["trace[1671569504] 'process raft request' (duration: 88.214833ms)","trace[1671569504] 'compare' (duration: 36.516229ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:34.202015Z","caller":"traceutil/trace.go:171","msg":"trace[400275581] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:519; }","duration":"123.786785ms","start":"2026-05-13T00:56:34.078213Z","end":"2026-05-13T00:56:34.202Z","steps":["trace[400275581] 'read index received' (duration: 84.045473ms)","trace[400275581] 'applied index is now lower than readState.Index' (duration: 39.740242ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:34.20207Z","caller":"traceutil/trace.go:171","msg":"trace[1054273037] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"127.512121ms","start":"2026-05-13T00:56:34.074528Z","end":"2026-05-13T00:56:34.20204Z","steps":["trace[1054273037] 'process raft request' (duration: 127.394638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:34.202171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.94047ms","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-05-13T00:56:34.202223Z","caller":"traceutil/trace.go:171","msg":"trace[1918428808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:504; }","duration":"124.030142ms","start":"2026-05-13T00:56:34.078177Z","end":"2026-05-13T00:56:34.202207Z","steps":["trace[1918428808] 'agreement among raft nodes before linearized reading' (duration: 123.912059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.669891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722303568,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-13T00:56:35.681502Z","caller":"traceutil/trace.go:171","msg":"trace[729970282] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"774.211265ms","start":"2026-05-13T00:56:34.907257Z","end":"2026-05-13T00:56:35.681468Z","steps":["trace[729970282] 'process raft request' (duration: 774.063561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.681641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.907228Z","time spent":"774.353859ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.693963Z","caller":"traceutil/trace.go:171","msg":"trace[1506221702] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"775.851688ms","start":"2026-05-13T00:56:34.918094Z","end":"2026-05-13T00:56:35.693945Z","steps":["trace[1506221702] 'process raft request' (duration: 775.773796ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:35.693955Z","caller":"traceutil/trace.go:171","msg":"trace[1011627316] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"785.589435ms","start":"2026-05-13T00:56:34.908318Z","end":"2026-05-13T00:56:35.693908Z","steps":["trace[1011627316] 'process raft request' (duration: 785.468361ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:35.694023Z","caller":"traceutil/trace.go:171","msg":"trace[1643191685] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"772.344027ms","start":"2026-05-13T00:56:34.921654Z","end":"2026-05-13T00:56:35.693998Z","steps":["trace[1643191685] 'process raft request' (duration: 772.257665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.918083Z","time spent":"775.92857ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.694082Z","caller":"traceutil/trace.go:171","msg":"trace[1456270093] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"771.73801ms","start":"2026-05-13T00:56:34.922302Z","end":"2026-05-13T00:56:35.69404Z","steps":["trace[1456270093] 'process raft request' (duration: 771.631957ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:35.694023Z","caller":"traceutil/trace.go:171","msg":"trace[1349417554] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"779.266804ms","start":"2026-05-13T00:56:34.914715Z","end":"2026-05-13T00:56:35.693982Z","steps":["trace[1349417554] 'process raft request' (duration: 779.13226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.921645Z","time spent":"772.4424ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.694229Z","caller":"traceutil/trace.go:171","msg":"trace[395323096] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"773.849809ms","start":"2026-05-13T00:56:34.920371Z","end":"2026-05-13T00:56:35.694221Z","steps":["trace[395323096] 'process raft request' (duration: 773.5228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.914696Z","time spent":"779.46999ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T00:56:35.694179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.922295Z","time spent":"771.842253ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.694302Z","caller":"traceutil/trace.go:171","msg":"trace[1779739524] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"755.713373ms","start":"2026-05-13T00:56:34.938581Z","end":"2026-05-13T00:56:35.694294Z","steps":["trace[1779739524] 'process raft request' (duration: 755.403875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.938562Z","time spent":"755.763564ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T00:56:35.694356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.920351Z","time spent":"773.909481ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.694398Z","caller":"traceutil/trace.go:171","msg":"trace[1220886096] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"771.109672ms","start":"2026-05-13T00:56:34.923283Z","end":"2026-05-13T00:56:35.694392Z","steps":["trace[1220886096] 'process raft request' (duration: 770.67417ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:35.694407Z","caller":"traceutil/trace.go:171","msg":"trace[1544207663] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"753.718646ms","start":"2026-05-13T00:56:34.940679Z","end":"2026-05-13T00:56:35.694398Z","steps":["trace[1544207663] 'process raft request' (duration: 753.391067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.923277Z","time spent":"771.139483ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.694437Z","caller":"traceutil/trace.go:171","msg":"trace[1639484003] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:522; }","duration":"525.080038ms","start":"2026-05-13T00:56:35.169328Z","end":"2026-05-13T00:56:35.694408Z","steps":["trace[1639484003] 'read index received' (duration: 512.037102ms)","trace[1639484003] 'applied index is now lower than readState.Index' (duration: 13.042496ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:35.694481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.940669Z","time spent":"753.760018ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:35.694508Z","caller":"traceutil/trace.go:171","msg":"trace[1385970865] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"726.995087ms","start":"2026-05-13T00:56:34.967501Z","end":"2026-05-13T00:56:35.694496Z","steps":["trace[1385970865] 'process raft request' (duration: 726.658097ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:35.694515Z","caller":"traceutil/trace.go:171","msg":"trace[558448073] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"621.098073ms","start":"2026-05-13T00:56:35.073407Z","end":"2026-05-13T00:56:35.694505Z","steps":["trace[558448073] 'process raft request' (duration: 620.893796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.210243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:35.694596Z","caller":"traceutil/trace.go:171","msg":"trace[780278188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"525.305955ms","start":"2026-05-13T00:56:35.169284Z","end":"2026-05-13T00:56:35.69459Z","steps":["trace[780278188] 'agreement among raft nodes before linearized reading' (duration: 525.214633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:35.694574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:35.073379Z","time spent":"621.175795ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T00:56:35.694651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:35.169269Z","time spent":"525.376647ms","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T00:56:35.694589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.967473Z","time spent":"727.080219ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T00:56:35.694847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:34.908293Z","time spent":"786.135729ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-13T00:56:36.956129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.998021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-13T00:56:36.956277Z","caller":"traceutil/trace.go:171","msg":"trace[815802280] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:580; }","duration":"303.162846ms","start":"2026-05-13T00:56:36.653067Z","end":"2026-05-13T00:56:36.95623Z","steps":["trace[815802280] 'range keys from in-memory index tree' (duration: 302.789765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:36.956289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.702221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-05-13T00:56:36.956406Z","caller":"traceutil/trace.go:171","msg":"trace[341509188] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:580; }","duration":"223.874106ms","start":"2026-05-13T00:56:36.732508Z","end":"2026-05-13T00:56:36.956382Z","steps":["trace[341509188] 'range keys from in-memory index tree' (duration: 223.418134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:36.956321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:36.65305Z","time spent":"303.260598ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29591,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-13T00:56:36.964857Z","caller":"traceutil/trace.go:171","msg":"trace[1871963437] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"195.888347ms","start":"2026-05-13T00:56:36.768938Z","end":"2026-05-13T00:56:36.964826Z","steps":["trace[1871963437] 'process raft request' (duration: 195.425104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:42.328451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722303742,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:56:42.520973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.263196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8j4db\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-13T00:56:42.521324Z","caller":"traceutil/trace.go:171","msg":"trace[1102700840] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8j4db; range_end:; response_count:1; response_revision:610; }","duration":"924.665587ms","start":"2026-05-13T00:56:41.596634Z","end":"2026-05-13T00:56:42.5213Z","steps":["trace[1102700840] 'range keys from in-memory index tree' (duration: 924.046731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:42.521382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:41.596616Z","time spent":"924.752589ms","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16986,"request content":"key:\"/registry/pods/kube-system/cilium-8j4db\" "} {"level":"warn","ts":"2026-05-13T00:56:42.521775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.122118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T00:56:42.521971Z","caller":"traceutil/trace.go:171","msg":"trace[383047949] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"693.557088ms","start":"2026-05-13T00:56:41.828398Z","end":"2026-05-13T00:56:42.521956Z","steps":["trace[383047949] 'read index received' (duration: 544.896947ms)","trace[383047949] 'applied index is now lower than readState.Index' (duration: 148.659271ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:42.522015Z","caller":"traceutil/trace.go:171","msg":"trace[1583083820] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"871.362131ms","start":"2026-05-13T00:56:41.65062Z","end":"2026-05-13T00:56:42.521982Z","steps":["trace[1583083820] 'process raft request' (duration: 722.954946ms)","trace[1583083820] 'compare' (duration: 147.815839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:42.522066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.667841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-13T00:56:42.52209Z","caller":"traceutil/trace.go:171","msg":"trace[1529341053] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:611; }","duration":"693.733963ms","start":"2026-05-13T00:56:41.82835Z","end":"2026-05-13T00:56:42.522084Z","steps":["trace[1529341053] 'agreement among raft nodes before linearized reading' (duration: 693.680311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:42.522122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:41.82833Z","time spent":"693.784824ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-05-13T00:56:42.522174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.641461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-13T00:56:42.522208Z","caller":"traceutil/trace.go:171","msg":"trace[1748345802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"274.695811ms","start":"2026-05-13T00:56:42.247501Z","end":"2026-05-13T00:56:42.522197Z","steps":["trace[1748345802] 'agreement among raft nodes before linearized reading' (duration: 274.62527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:42.522205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.726712ms","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-05-13T00:56:42.522256Z","caller":"traceutil/trace.go:171","msg":"trace[53090535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"251.806435ms","start":"2026-05-13T00:56:42.270434Z","end":"2026-05-13T00:56:42.522241Z","steps":["trace[53090535] 'agreement among raft nodes before linearized reading' (duration: 251.698572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:42.522317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.030985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-13T00:56:42.522299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.923537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-13T00:56:42.522392Z","caller":"traceutil/trace.go:171","msg":"trace[1746135543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"279.102756ms","start":"2026-05-13T00:56:42.243242Z","end":"2026-05-13T00:56:42.522344Z","steps":["trace[1746135543] 'agreement among raft nodes before linearized reading' (duration: 278.994793ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:42.522422Z","caller":"traceutil/trace.go:171","msg":"trace[358408178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"427.122732ms","start":"2026-05-13T00:56:42.095269Z","end":"2026-05-13T00:56:42.522392Z","steps":["trace[358408178] 'agreement among raft nodes before linearized reading' (duration: 426.831255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:42.522475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.095247Z","time spent":"427.218815ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-13T00:56:42.522641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:41.650596Z","time spent":"871.476964ms","remote":"127.0.0.1:43216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.294217Z","caller":"traceutil/trace.go:171","msg":"trace[449921506] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"767.462157ms","start":"2026-05-13T00:56:42.526722Z","end":"2026-05-13T00:56:43.294184Z","steps":["trace[449921506] 'process raft request' (duration: 767.242942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.294424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.526693Z","time spent":"767.643841ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.294492Z","caller":"traceutil/trace.go:171","msg":"trace[1139091909] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"767.703754ms","start":"2026-05-13T00:56:42.526761Z","end":"2026-05-13T00:56:43.294465Z","steps":["trace[1139091909] 'process raft request' (duration: 767.380465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.29464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.526716Z","time spent":"767.844688ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.294799Z","caller":"traceutil/trace.go:171","msg":"trace[346760351] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"766.338399ms","start":"2026-05-13T00:56:42.528449Z","end":"2026-05-13T00:56:43.294787Z","steps":["trace[346760351] 'process raft request' (duration: 765.939348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.294886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.528438Z","time spent":"766.41508ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.295071Z","caller":"traceutil/trace.go:171","msg":"trace[1151161703] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"765.401453ms","start":"2026-05-13T00:56:42.529657Z","end":"2026-05-13T00:56:43.295059Z","steps":["trace[1151161703] 'process raft request' (duration: 765.081515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.295145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.529638Z","time spent":"765.472836ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.295274Z","caller":"traceutil/trace.go:171","msg":"trace[711869609] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"713.368155ms","start":"2026-05-13T00:56:42.581894Z","end":"2026-05-13T00:56:43.295262Z","steps":["trace[711869609] 'process raft request' (duration: 713.314093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.295355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.581868Z","time spent":"713.444826ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:43.295365Z","caller":"traceutil/trace.go:171","msg":"trace[1163367532] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"757.754043ms","start":"2026-05-13T00:56:42.537601Z","end":"2026-05-13T00:56:43.295355Z","steps":["trace[1163367532] 'process raft request' (duration: 757.396054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.295421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.53758Z","time spent":"757.814105ms","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.415069Z","caller":"traceutil/trace.go:171","msg":"trace[464495920] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:634; }","duration":"245.990415ms","start":"2026-05-13T00:56:43.169049Z","end":"2026-05-13T00:56:43.41504Z","steps":["trace[464495920] 'read index received' (duration: 124.974568ms)","trace[464495920] 'applied index is now lower than readState.Index' (duration: 121.014707ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:43.415086Z","caller":"traceutil/trace.go:171","msg":"trace[1176713618] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"826.661951ms","start":"2026-05-13T00:56:42.588398Z","end":"2026-05-13T00:56:43.41506Z","steps":["trace[1176713618] 'process raft request' (duration: 729.314105ms)","trace[1176713618] 'compare' (duration: 97.215912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:43.415236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.17619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:43.415266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:42.58838Z","time spent":"826.812554ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:43.415305Z","caller":"traceutil/trace.go:171","msg":"trace[1608128513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"246.296013ms","start":"2026-05-13T00:56:43.168993Z","end":"2026-05-13T00:56:43.415289Z","steps":["trace[1608128513] 'agreement among raft nodes before linearized reading' (duration: 246.14826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:43.41549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.169616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:43.415532Z","caller":"traceutil/trace.go:171","msg":"trace[244401479] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"110.254068ms","start":"2026-05-13T00:56:43.305265Z","end":"2026-05-13T00:56:43.415519Z","steps":["trace[244401479] 'agreement among raft nodes before linearized reading' (duration: 110.200017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:44.670276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722303760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:56:45.170851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722303760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:56:45.23399Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419767698s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T00:56:45.235144Z","caller":"traceutil/trace.go:171","msg":"trace[1459122601] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.425489096s","start":"2026-05-13T00:56:43.809622Z","end":"2026-05-13T00:56:45.235111Z","steps":["trace[1459122601] 'process raft request' (duration: 1.424839799s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:45.235324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:43.809592Z","time spent":"1.42563728s","remote":"127.0.0.1:43216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:45.250062Z","caller":"traceutil/trace.go:171","msg":"trace[1112641533] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"1.080748365s","start":"2026-05-13T00:56:44.169279Z","end":"2026-05-13T00:56:45.250027Z","steps":["trace[1112641533] 'read index received' (duration: 1.064980806s)","trace[1112641533] 'applied index is now lower than readState.Index' (duration: 15.766059ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:45.250111Z","caller":"traceutil/trace.go:171","msg":"trace[503238375] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.01638078s","start":"2026-05-13T00:56:44.233688Z","end":"2026-05-13T00:56:45.250068Z","steps":["trace[503238375] 'process raft request' (duration: 1.016290517s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:45.2502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080902338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:45.250214Z","caller":"traceutil/trace.go:171","msg":"trace[189978525] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.28857365s","start":"2026-05-13T00:56:43.961616Z","end":"2026-05-13T00:56:45.250189Z","steps":["trace[189978525] 'process raft request' (duration: 1.288217671s)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:45.250238Z","caller":"traceutil/trace.go:171","msg":"trace[1989366298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.081005611s","start":"2026-05-13T00:56:44.169219Z","end":"2026-05-13T00:56:45.250224Z","steps":["trace[1989366298] 'agreement among raft nodes before linearized reading' (duration: 1.080922509s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:45.250274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:44.233655Z","time spent":"1.016530693s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T00:56:45.250279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:44.169198Z","time spent":"1.081072953s","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-13T00:56:45.25033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:43.961594Z","time spent":"1.288671651s","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:45.735932Z","caller":"traceutil/trace.go:171","msg":"trace[1694645390] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"124.755336ms","start":"2026-05-13T00:56:45.611145Z","end":"2026-05-13T00:56:45.735901Z","steps":["trace[1694645390] 'process raft request' (duration: 124.53193ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:45.738755Z","caller":"traceutil/trace.go:171","msg":"trace[1448190143] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"115.236734ms","start":"2026-05-13T00:56:45.623501Z","end":"2026-05-13T00:56:45.738738Z","steps":["trace[1448190143] 'process raft request' (duration: 115.105031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.330257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.596921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.330336Z","caller":"traceutil/trace.go:171","msg":"trace[1584786060] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:654; }","duration":"463.705693ms","start":"2026-05-13T00:56:46.866611Z","end":"2026-05-13T00:56:47.330317Z","steps":["trace[1584786060] 'count revisions from in-memory index tree' (duration: 463.54312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.330427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.866606Z","time spent":"463.806905ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:56:47.330597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.420462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.330677Z","caller":"traceutil/trace.go:171","msg":"trace[2029148415] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:654; }","duration":"464.553374ms","start":"2026-05-13T00:56:46.866104Z","end":"2026-05-13T00:56:47.330657Z","steps":["trace[2029148415] 'range keys from in-memory index tree' (duration: 464.341549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.330717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.866083Z","time spent":"464.623716ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T00:56:47.330694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.808015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.330847Z","caller":"traceutil/trace.go:171","msg":"trace[1775218156] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:654; }","duration":"464.237206ms","start":"2026-05-13T00:56:46.866568Z","end":"2026-05-13T00:56:47.330805Z","steps":["trace[1775218156] 'range keys from in-memory index tree' (duration: 463.681162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.330931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.866559Z","time spent":"464.327528ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-13T00:56:47.33505Z","caller":"traceutil/trace.go:171","msg":"trace[219907861] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"443.417004ms","start":"2026-05-13T00:56:46.891597Z","end":"2026-05-13T00:56:47.335014Z","steps":["trace[219907861] 'read index received' (duration: 443.219399ms)","trace[219907861] 'applied index is now lower than readState.Index' (duration: 196.115µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:47.335133Z","caller":"traceutil/trace.go:171","msg":"trace[318901307] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"462.269056ms","start":"2026-05-13T00:56:46.872841Z","end":"2026-05-13T00:56:47.33511Z","steps":["trace[318901307] 'process raft request' (duration: 461.969869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.335224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.610178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.335287Z","caller":"traceutil/trace.go:171","msg":"trace[1986589338] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:655; }","duration":"443.699231ms","start":"2026-05-13T00:56:46.891574Z","end":"2026-05-13T00:56:47.335273Z","steps":["trace[1986589338] 'agreement among raft nodes before linearized reading' (duration: 443.576657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.33533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.891565Z","time spent":"443.754843ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T00:56:47.335238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.872821Z","time spent":"462.362788ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:47.338295Z","caller":"traceutil/trace.go:171","msg":"trace[2088249912] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"265.382165ms","start":"2026-05-13T00:56:47.072883Z","end":"2026-05-13T00:56:47.338265Z","steps":["trace[2088249912] 'process raft request' (duration: 265.147458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.342191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.493239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.34223Z","caller":"traceutil/trace.go:171","msg":"trace[1017533653] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:657; }","duration":"450.574771ms","start":"2026-05-13T00:56:46.891647Z","end":"2026-05-13T00:56:47.342222Z","steps":["trace[1017533653] 'agreement among raft nodes before linearized reading' (duration: 450.490209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.342255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.891625Z","time spent":"450.623052ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:56:47.342256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.260905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.342317Z","caller":"traceutil/trace.go:171","msg":"trace[2017919762] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:657; }","duration":"249.319158ms","start":"2026-05-13T00:56:47.092959Z","end":"2026-05-13T00:56:47.342278Z","steps":["trace[2017919762] 'agreement among raft nodes before linearized reading' (duration: 249.265625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.342382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.505022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.34241Z","caller":"traceutil/trace.go:171","msg":"trace[1471898611] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:657; }","duration":"249.546553ms","start":"2026-05-13T00:56:47.092856Z","end":"2026-05-13T00:56:47.342403Z","steps":["trace[1471898611] 'agreement among raft nodes before linearized reading' (duration: 249.502412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.342506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.943091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.34253Z","caller":"traceutil/trace.go:171","msg":"trace[305320137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:657; }","duration":"276.967762ms","start":"2026-05-13T00:56:47.065557Z","end":"2026-05-13T00:56:47.342524Z","steps":["trace[305320137] 'agreement among raft nodes before linearized reading' (duration: 276.932051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.342635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.629478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.342658Z","caller":"traceutil/trace.go:171","msg":"trace[1459450899] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:657; }","duration":"277.68051ms","start":"2026-05-13T00:56:47.064973Z","end":"2026-05-13T00:56:47.342653Z","steps":["trace[1459450899] 'agreement among raft nodes before linearized reading' (duration: 277.646009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.34274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.743452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.342764Z","caller":"traceutil/trace.go:171","msg":"trace[311263072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:657; }","duration":"277.795872ms","start":"2026-05-13T00:56:47.064962Z","end":"2026-05-13T00:56:47.342757Z","steps":["trace[311263072] 'agreement among raft nodes before linearized reading' (duration: 277.762811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.34287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.978246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.342895Z","caller":"traceutil/trace.go:171","msg":"trace[1964103769] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:657; }","duration":"305.014516ms","start":"2026-05-13T00:56:47.037873Z","end":"2026-05-13T00:56:47.342887Z","steps":["trace[1964103769] 'agreement among raft nodes before linearized reading' (duration: 304.979236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.342915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:47.037867Z","time spent":"305.043057ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-05-13T00:56:47.343018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.9773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.343041Z","caller":"traceutil/trace.go:171","msg":"trace[582248198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:657; }","duration":"306.014312ms","start":"2026-05-13T00:56:47.037022Z","end":"2026-05-13T00:56:47.343036Z","steps":["trace[582248198] 'agreement among raft nodes before linearized reading' (duration: 305.981451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.34306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:47.037017Z","time spent":"306.036642ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:56:47.34317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.208976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.343193Z","caller":"traceutil/trace.go:171","msg":"trace[918209648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:657; }","duration":"306.239807ms","start":"2026-05-13T00:56:47.036948Z","end":"2026-05-13T00:56:47.343188Z","steps":["trace[918209648] 'agreement among raft nodes before linearized reading' (duration: 306.199916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.343212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:47.036903Z","time spent":"306.303138ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T00:56:47.343306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.504311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.343342Z","caller":"traceutil/trace.go:171","msg":"trace[192427702] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:657; }","duration":"345.542393ms","start":"2026-05-13T00:56:46.997786Z","end":"2026-05-13T00:56:47.343328Z","steps":["trace[192427702] 'agreement among raft nodes before linearized reading' (duration: 345.499342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.343369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.997782Z","time spent":"345.580174ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:56:47.343474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.695266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.343498Z","caller":"traceutil/trace.go:171","msg":"trace[1192186708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:657; }","duration":"345.741387ms","start":"2026-05-13T00:56:46.99775Z","end":"2026-05-13T00:56:47.343492Z","steps":["trace[1192186708] 'agreement among raft nodes before linearized reading' (duration: 345.706866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.343522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.997735Z","time spent":"345.781229ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T00:56:47.343626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.550729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.34365Z","caller":"traceutil/trace.go:171","msg":"trace[904808970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:657; }","duration":"402.59178ms","start":"2026-05-13T00:56:46.941052Z","end":"2026-05-13T00:56:47.343644Z","steps":["trace[904808970] 'agreement among raft nodes before linearized reading' (duration: 402.549689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.343675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.941046Z","time spent":"402.623421ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:56:47.343778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.740485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.343802Z","caller":"traceutil/trace.go:171","msg":"trace[1422977208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:657; }","duration":"402.780265ms","start":"2026-05-13T00:56:46.941016Z","end":"2026-05-13T00:56:47.343796Z","steps":["trace[1422977208] 'agreement among raft nodes before linearized reading' (duration: 402.746734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.343822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.941009Z","time spent":"402.806696ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-13T00:56:47.343906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.025412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.343928Z","caller":"traceutil/trace.go:171","msg":"trace[1969255166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:657; }","duration":"403.070182ms","start":"2026-05-13T00:56:46.940853Z","end":"2026-05-13T00:56:47.343923Z","steps":["trace[1969255166] 'agreement among raft nodes before linearized reading' (duration: 403.036792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.343946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.940841Z","time spent":"403.100113ms","remote":"127.0.0.1:42662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T00:56:47.344045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.107729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.34407Z","caller":"traceutil/trace.go:171","msg":"trace[1342518211] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:657; }","duration":"428.149771ms","start":"2026-05-13T00:56:46.915912Z","end":"2026-05-13T00:56:47.344062Z","steps":["trace[1342518211] 'agreement among raft nodes before linearized reading' (duration: 428.10849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.34409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.409794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:47.344102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.9159Z","time spent":"428.191791ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-05-13T00:56:47.344124Z","caller":"traceutil/trace.go:171","msg":"trace[816418450] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:657; }","duration":"118.459465ms","start":"2026-05-13T00:56:47.225656Z","end":"2026-05-13T00:56:47.344115Z","steps":["trace[816418450] 'agreement among raft nodes before linearized reading' (duration: 118.410344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.344211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.9546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:47.344224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.922956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.344237Z","caller":"traceutil/trace.go:171","msg":"trace[1832628839] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:657; }","duration":"429.012563ms","start":"2026-05-13T00:56:46.915217Z","end":"2026-05-13T00:56:47.34423Z","steps":["trace[1832628839] 'agreement among raft nodes before linearized reading' (duration: 428.974702ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:47.344244Z","caller":"traceutil/trace.go:171","msg":"trace[186677774] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:657; }","duration":"118.990988ms","start":"2026-05-13T00:56:47.225247Z","end":"2026-05-13T00:56:47.344238Z","steps":["trace[186677774] 'agreement among raft nodes before linearized reading' (duration: 118.960767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.344262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.9152Z","time spent":"429.055733ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:56:47.344326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.123881ms","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-05-13T00:56:47.344353Z","caller":"traceutil/trace.go:171","msg":"trace[1232323877] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:657; }","duration":"119.163943ms","start":"2026-05-13T00:56:47.225184Z","end":"2026-05-13T00:56:47.344347Z","steps":["trace[1232323877] 'agreement among raft nodes before linearized reading' (duration: 119.127502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.344372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.224917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.344432Z","caller":"traceutil/trace.go:171","msg":"trace[42450669] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:657; }","duration":"429.277339ms","start":"2026-05-13T00:56:46.915118Z","end":"2026-05-13T00:56:47.344396Z","steps":["trace[42450669] 'agreement among raft nodes before linearized reading' (duration: 429.235268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.344458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.915104Z","time spent":"429.3472ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-13T00:56:47.344465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.476941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.344487Z","caller":"traceutil/trace.go:171","msg":"trace[1188310671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"176.519413ms","start":"2026-05-13T00:56:47.16796Z","end":"2026-05-13T00:56:47.344479Z","steps":["trace[1188310671] 'agreement among raft nodes before linearized reading' (duration: 176.486012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.344552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.187331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.344576Z","caller":"traceutil/trace.go:171","msg":"trace[1534177636] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:657; }","duration":"452.234133ms","start":"2026-05-13T00:56:46.892336Z","end":"2026-05-13T00:56:47.34457Z","steps":["trace[1534177636] 'agreement among raft nodes before linearized reading' (duration: 452.200012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:47.3446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:46.892325Z","time spent":"452.269243ms","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-13T00:56:47.344605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.741471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:47.344921Z","caller":"traceutil/trace.go:171","msg":"trace[1671060567] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:657; }","duration":"251.09016ms","start":"2026-05-13T00:56:47.093823Z","end":"2026-05-13T00:56:47.344913Z","steps":["trace[1671060567] 'agreement among raft nodes before linearized reading' (duration: 250.758302ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:48.193899Z","caller":"traceutil/trace.go:171","msg":"trace[1348714165] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"174.388306ms","start":"2026-05-13T00:56:48.019475Z","end":"2026-05-13T00:56:48.193863Z","steps":["trace[1348714165] 'process raft request' (duration: 174.235982ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:48.38224Z","caller":"traceutil/trace.go:171","msg":"trace[950533511] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:699; }","duration":"211.938985ms","start":"2026-05-13T00:56:48.170278Z","end":"2026-05-13T00:56:48.382217Z","steps":["trace[950533511] 'read index received' (duration: 23.527427ms)","trace[950533511] 'applied index is now lower than readState.Index' (duration: 188.410488ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:48.382292Z","caller":"traceutil/trace.go:171","msg":"trace[957967678] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"310.255425ms","start":"2026-05-13T00:56:48.07201Z","end":"2026-05-13T00:56:48.382265Z","steps":["trace[957967678] 'process raft request' (duration: 293.801143ms)","trace[957967678] 'compare' (duration: 16.208517ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:48.382334Z","caller":"traceutil/trace.go:171","msg":"trace[1271687974] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"247.350853ms","start":"2026-05-13T00:56:48.134949Z","end":"2026-05-13T00:56:48.3823Z","steps":["trace[1271687974] 'process raft request' (duration: 247.19706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:48.382385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.090359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:48.382423Z","caller":"traceutil/trace.go:171","msg":"trace[1729277791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"212.180831ms","start":"2026-05-13T00:56:48.170232Z","end":"2026-05-13T00:56:48.382413Z","steps":["trace[1729277791] 'agreement among raft nodes before linearized reading' (duration: 212.102909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:48.382515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:48.071974Z","time spent":"310.384708ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-13T00:56:48.419751Z","caller":"traceutil/trace.go:171","msg":"trace[1683910024] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"220.211908ms","start":"2026-05-13T00:56:48.199504Z","end":"2026-05-13T00:56:48.419716Z","steps":["trace[1683910024] 'process raft request' (duration: 220.033454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:48.420024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.006633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:48.420093Z","caller":"traceutil/trace.go:171","msg":"trace[732005200] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:679; }","duration":"149.127596ms","start":"2026-05-13T00:56:48.270948Z","end":"2026-05-13T00:56:48.420076Z","steps":["trace[732005200] 'agreement among raft nodes before linearized reading' (duration: 149.011763ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:48.636508Z","caller":"traceutil/trace.go:171","msg":"trace[2099719412] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"216.777446ms","start":"2026-05-13T00:56:48.419684Z","end":"2026-05-13T00:56:48.636461Z","steps":["trace[2099719412] 'read index received' (duration: 117.270566ms)","trace[2099719412] 'applied index is now lower than readState.Index' (duration: 99.50538ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:48.636966Z","caller":"traceutil/trace.go:171","msg":"trace[830992834] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"249.741893ms","start":"2026-05-13T00:56:48.387184Z","end":"2026-05-13T00:56:48.636926Z","steps":["trace[830992834] 'process raft request' (duration: 149.783702ms)","trace[830992834] 'compare' (duration: 99.373947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:48.637074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.748852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:48.637169Z","caller":"traceutil/trace.go:171","msg":"trace[2106917175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"253.869365ms","start":"2026-05-13T00:56:48.383267Z","end":"2026-05-13T00:56:48.637137Z","steps":["trace[2106917175] 'agreement among raft nodes before linearized reading' (duration: 253.746712ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:48.676341Z","caller":"traceutil/trace.go:171","msg":"trace[1991690951] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"251.481325ms","start":"2026-05-13T00:56:48.424828Z","end":"2026-05-13T00:56:48.67631Z","steps":["trace[1991690951] 'process raft request' (duration: 251.339602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:48.728952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.582936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:48.729043Z","caller":"traceutil/trace.go:171","msg":"trace[1600858613] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:682; }","duration":"257.734189ms","start":"2026-05-13T00:56:48.471286Z","end":"2026-05-13T00:56:48.72902Z","steps":["trace[1600858613] 'agreement among raft nodes before linearized reading' (duration: 257.584126ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:48.899168Z","caller":"traceutil/trace.go:171","msg":"trace[1777167146] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"170.348415ms","start":"2026-05-13T00:56:48.728787Z","end":"2026-05-13T00:56:48.899135Z","steps":["trace[1777167146] 'read index received' (duration: 118.372251ms)","trace[1777167146] 'applied index is now lower than readState.Index' (duration: 51.975054ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:48.899242Z","caller":"traceutil/trace.go:171","msg":"trace[1681646835] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"214.409236ms","start":"2026-05-13T00:56:48.68477Z","end":"2026-05-13T00:56:48.899179Z","steps":["trace[1681646835] 'process raft request' (duration: 162.374611ms)","trace[1681646835] 'compare' (duration: 51.871451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:48.899326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.304343ms","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-05-13T00:56:48.899403Z","caller":"traceutil/trace.go:171","msg":"trace[1766363178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"216.428486ms","start":"2026-05-13T00:56:48.682956Z","end":"2026-05-13T00:56:48.899384Z","steps":["trace[1766363178] 'agreement among raft nodes before linearized reading' (duration: 216.20262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:48.899567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.350443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-13T00:56:48.899619Z","caller":"traceutil/trace.go:171","msg":"trace[848375953] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"229.449485ms","start":"2026-05-13T00:56:48.670155Z","end":"2026-05-13T00:56:48.899605Z","steps":["trace[848375953] 'agreement among raft nodes before linearized reading' (duration: 229.118087ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:48.90391Z","caller":"traceutil/trace.go:171","msg":"trace[1403877443] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"159.807378ms","start":"2026-05-13T00:56:48.744087Z","end":"2026-05-13T00:56:48.903894Z","steps":["trace[1403877443] 'process raft request' (duration: 159.319345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.475556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.170661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T00:56:49.475696Z","caller":"traceutil/trace.go:171","msg":"trace[1360523201] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"571.712019ms","start":"2026-05-13T00:56:48.903957Z","end":"2026-05-13T00:56:49.475669Z","steps":["trace[1360523201] 'process raft request' (duration: 267.312772ms)","trace[1360523201] 'compare' (duration: 304.066079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:49.475783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:48.903934Z","time spent":"571.804951ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:49.535994Z","caller":"traceutil/trace.go:171","msg":"trace[1702102775] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"464.690198ms","start":"2026-05-13T00:56:49.071275Z","end":"2026-05-13T00:56:49.535965Z","steps":["trace[1702102775] 'read index received' (duration: 100.09044ms)","trace[1702102775] 'applied index is now lower than readState.Index' (duration: 364.598818ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:56:49.536159Z","caller":"traceutil/trace.go:171","msg":"trace[1458495602] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"624.275691ms","start":"2026-05-13T00:56:48.911837Z","end":"2026-05-13T00:56:49.536112Z","steps":["trace[1458495602] 'process raft request' (duration: 623.985014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.536342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:48.911813Z","time spent":"624.434596ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T00:56:49.536366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.073787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-05-13T00:56:49.53646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.670716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-05-13T00:56:49.53647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.31645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T00:56:49.536476Z","caller":"traceutil/trace.go:171","msg":"trace[1585308994] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:686; }","duration":"465.241181ms","start":"2026-05-13T00:56:49.071217Z","end":"2026-05-13T00:56:49.536458Z","steps":["trace[1585308994] 'agreement among raft nodes before linearized reading' (duration: 464.963384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.536579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:49.071196Z","time spent":"465.369544ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9341,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-13T00:56:49.536615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.527936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T00:56:49.536655Z","caller":"traceutil/trace.go:171","msg":"trace[1811413666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"179.592568ms","start":"2026-05-13T00:56:49.357053Z","end":"2026-05-13T00:56:49.536646Z","steps":["trace[1811413666] 'agreement among raft nodes before linearized reading' (duration: 179.490885ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:49.536523Z","caller":"traceutil/trace.go:171","msg":"trace[906066842] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:686; }","duration":"265.764908ms","start":"2026-05-13T00:56:49.270738Z","end":"2026-05-13T00:56:49.536503Z","steps":["trace[906066842] 'agreement among raft nodes before linearized reading' (duration: 265.503441ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:49.536522Z","caller":"traceutil/trace.go:171","msg":"trace[776130687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"179.384183ms","start":"2026-05-13T00:56:49.357125Z","end":"2026-05-13T00:56:49.536509Z","steps":["trace[776130687] 'agreement among raft nodes before linearized reading' (duration: 179.25884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.536856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.949307ms","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-05-13T00:56:49.536898Z","caller":"traceutil/trace.go:171","msg":"trace[1335156217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"178.024009ms","start":"2026-05-13T00:56:49.358862Z","end":"2026-05-13T00:56:49.536886Z","steps":["trace[1335156217] 'agreement among raft nodes before linearized reading' (duration: 177.791204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.537025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.240659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T00:56:49.537226Z","caller":"traceutil/trace.go:171","msg":"trace[15078965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"179.455194ms","start":"2026-05-13T00:56:49.357724Z","end":"2026-05-13T00:56:49.537179Z","steps":["trace[15078965] 'agreement among raft nodes before linearized reading' (duration: 178.615325ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:49.873454Z","caller":"traceutil/trace.go:171","msg":"trace[695729690] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"329.545267ms","start":"2026-05-13T00:56:49.543878Z","end":"2026-05-13T00:56:49.873423Z","steps":["trace[695729690] 'process raft request' (duration: 329.387333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.873628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:49.543855Z","time spent":"329.684161ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:49.884024Z","caller":"traceutil/trace.go:171","msg":"trace[1263772596] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"339.912189ms","start":"2026-05-13T00:56:49.544079Z","end":"2026-05-13T00:56:49.883991Z","steps":["trace[1263772596] 'process raft request' (duration: 339.741975ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:49.884074Z","caller":"traceutil/trace.go:171","msg":"trace[1150993277] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"339.081068ms","start":"2026-05-13T00:56:49.544967Z","end":"2026-05-13T00:56:49.884048Z","steps":["trace[1150993277] 'process raft request' (duration: 338.971076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.884189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:49.544054Z","time spent":"340.048722ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T00:56:49.884224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:49.544959Z","time spent":"339.190992ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:49.884332Z","caller":"traceutil/trace.go:171","msg":"trace[2016594224] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"339.704005ms","start":"2026-05-13T00:56:49.544618Z","end":"2026-05-13T00:56:49.884322Z","steps":["trace[2016594224] 'process raft request' (duration: 339.285175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.88439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:49.544608Z","time spent":"339.753896ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:49.884405Z","caller":"traceutil/trace.go:171","msg":"trace[2002404721] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"208.624522ms","start":"2026-05-13T00:56:49.675763Z","end":"2026-05-13T00:56:49.884387Z","steps":["trace[2002404721] 'process raft request' (duration: 208.256274ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:49.884335Z","caller":"traceutil/trace.go:171","msg":"trace[1975829745] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"338.982507ms","start":"2026-05-13T00:56:49.545335Z","end":"2026-05-13T00:56:49.884317Z","steps":["trace[1975829745] 'process raft request' (duration: 338.636208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:49.884535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:56:49.545328Z","time spent":"339.157731ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:56:52.019009Z","caller":"traceutil/trace.go:171","msg":"trace[161656816] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"154.334577ms","start":"2026-05-13T00:56:51.864646Z","end":"2026-05-13T00:56:52.018981Z","steps":["trace[161656816] 'read index received' (duration: 154.222215ms)","trace[161656816] 'applied index is now lower than readState.Index' (duration: 110.582µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:56:52.019182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.506901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:56:52.019263Z","caller":"traceutil/trace.go:171","msg":"trace[1877821531] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:712; }","duration":"154.634344ms","start":"2026-05-13T00:56:51.864608Z","end":"2026-05-13T00:56:52.019242Z","steps":["trace[1877821531] 'agreement among raft nodes before linearized reading' (duration: 154.499771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:52.019271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.524392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:56:52.019619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.104218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T00:56:52.019684Z","caller":"traceutil/trace.go:171","msg":"trace[1557151810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"132.176999ms","start":"2026-05-13T00:56:51.887486Z","end":"2026-05-13T00:56:52.019663Z","steps":["trace[1557151810] 'agreement among raft nodes before linearized reading' (duration: 132.041476ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:52.019599Z","caller":"traceutil/trace.go:171","msg":"trace[1176784689] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:712; }","duration":"154.886ms","start":"2026-05-13T00:56:51.864693Z","end":"2026-05-13T00:56:52.019579Z","steps":["trace[1176784689] 'agreement among raft nodes before linearized reading' (duration: 154.496331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:52.019478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.726265ms","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:5"} {"level":"info","ts":"2026-05-13T00:56:52.020427Z","caller":"traceutil/trace.go:171","msg":"trace[1250413554] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:712; }","duration":"155.68913ms","start":"2026-05-13T00:56:51.864716Z","end":"2026-05-13T00:56:52.020405Z","steps":["trace[1250413554] 'agreement among raft nodes before linearized reading' (duration: 154.694276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:52.019515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.219689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T00:56:52.020688Z","caller":"traceutil/trace.go:171","msg":"trace[1409214492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:712; }","duration":"143.418968ms","start":"2026-05-13T00:56:51.877251Z","end":"2026-05-13T00:56:52.02067Z","steps":["trace[1409214492] 'agreement among raft nodes before linearized reading' (duration: 142.147178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:56:52.019515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.159719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-13T00:56:52.019519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.33221ms","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-05-13T00:56:52.021014Z","caller":"traceutil/trace.go:171","msg":"trace[2025392499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"132.842775ms","start":"2026-05-13T00:56:51.888155Z","end":"2026-05-13T00:56:52.020998Z","steps":["trace[2025392499] 'agreement among raft nodes before linearized reading' (duration: 131.236288ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:52.021035Z","caller":"traceutil/trace.go:171","msg":"trace[1655334899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"133.659534ms","start":"2026-05-13T00:56:51.887318Z","end":"2026-05-13T00:56:52.020978Z","steps":["trace[1655334899] 'agreement among raft nodes before linearized reading' (duration: 131.992925ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:56:59.7225Z","caller":"traceutil/trace.go:171","msg":"trace[128647979] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"174.002373ms","start":"2026-05-13T00:56:59.548476Z","end":"2026-05-13T00:56:59.722479Z","steps":["trace[128647979] 'process raft request' (duration: 173.84156ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:01.962667Z","caller":"traceutil/trace.go:171","msg":"trace[2041614255] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"132.670279ms","start":"2026-05-13T00:57:01.829977Z","end":"2026-05-13T00:57:01.962648Z","steps":["trace[2041614255] 'read index received' (duration: 36.103478ms)","trace[2041614255] 'applied index is now lower than readState.Index' (duration: 96.565691ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:01.962926Z","caller":"traceutil/trace.go:171","msg":"trace[1130213674] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"228.141577ms","start":"2026-05-13T00:57:01.734757Z","end":"2026-05-13T00:57:01.962898Z","steps":["trace[1130213674] 'process raft request' (duration: 131.369091ms)","trace[1130213674] 'compare' (duration: 96.190222ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:01.962949Z","caller":"traceutil/trace.go:171","msg":"trace[180702413] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"228.112605ms","start":"2026-05-13T00:57:01.734806Z","end":"2026-05-13T00:57:01.962919Z","steps":["trace[180702413] 'process raft request' (duration: 227.751287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:01.963637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.651069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-13T00:57:01.9637Z","caller":"traceutil/trace.go:171","msg":"trace[801735609] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:783; }","duration":"133.773972ms","start":"2026-05-13T00:57:01.829914Z","end":"2026-05-13T00:57:01.963688Z","steps":["trace[801735609] 'agreement among raft nodes before linearized reading' (duration: 132.817372ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:02.11799Z","caller":"traceutil/trace.go:171","msg":"trace[1344860087] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"111.131133ms","start":"2026-05-13T00:57:02.006842Z","end":"2026-05-13T00:57:02.117973Z","steps":["trace[1344860087] 'read index received' (duration: 110.132901ms)","trace[1344860087] 'applied index is now lower than readState.Index' (duration: 997.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:02.118039Z","caller":"traceutil/trace.go:171","msg":"trace[1921735986] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"120.955404ms","start":"2026-05-13T00:57:01.997074Z","end":"2026-05-13T00:57:02.118029Z","steps":["trace[1921735986] 'process raft request' (duration: 119.948782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:02.118163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.304146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-13T00:57:02.118221Z","caller":"traceutil/trace.go:171","msg":"trace[647947042] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:785; }","duration":"111.374188ms","start":"2026-05-13T00:57:02.006838Z","end":"2026-05-13T00:57:02.118212Z","steps":["trace[647947042] 'agreement among raft nodes before linearized reading' (duration: 111.221664ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:02.458326Z","caller":"traceutil/trace.go:171","msg":"trace[1066530814] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"151.208197ms","start":"2026-05-13T00:57:02.307097Z","end":"2026-05-13T00:57:02.458305Z","steps":["trace[1066530814] 'process raft request' (duration: 151.094985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:03.670083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722304125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:57:04.170751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880895722304125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-13T00:57:04.249764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.278530878s","expected-duration":"1s"} {"level":"info","ts":"2026-05-13T00:57:04.250146Z","caller":"traceutil/trace.go:171","msg":"trace[1482683441] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"1.080077996s","start":"2026-05-13T00:57:03.170049Z","end":"2026-05-13T00:57:04.250127Z","steps":["trace[1482683441] 'read index received' (duration: 1.07982051s)","trace[1482683441] 'applied index is now lower than readState.Index' (duration: 256.416µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:04.250182Z","caller":"traceutil/trace.go:171","msg":"trace[1390911885] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.279052909s","start":"2026-05-13T00:57:02.971104Z","end":"2026-05-13T00:57:04.250157Z","steps":["trace[1390911885] 'process raft request' (duration: 1.278810944s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:04.250262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080203398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:57:04.250314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:02.971078Z","time spent":"1.279162001s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:57:04.250422Z","caller":"traceutil/trace.go:171","msg":"trace[1240407217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"1.080334181s","start":"2026-05-13T00:57:03.169996Z","end":"2026-05-13T00:57:04.25033Z","steps":["trace[1240407217] 'agreement among raft nodes before linearized reading' (duration: 1.080223908s)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:04.25056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.240568ms","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-05-13T00:57:04.250473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:03.169977Z","time spent":"1.080482914s","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T00:57:04.250636Z","caller":"traceutil/trace.go:171","msg":"trace[1175262778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"465.36676ms","start":"2026-05-13T00:57:03.78525Z","end":"2026-05-13T00:57:04.250617Z","steps":["trace[1175262778] 'agreement among raft nodes before linearized reading' (duration: 465.156176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:04.250695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:03.785229Z","time spent":"465.457192ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-13T00:57:04.250847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.5149ms","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-05-13T00:57:04.25088Z","caller":"traceutil/trace.go:171","msg":"trace[1589682923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"115.604092ms","start":"2026-05-13T00:57:04.135267Z","end":"2026-05-13T00:57:04.250871Z","steps":["trace[1589682923] 'agreement among raft nodes before linearized reading' (duration: 115.52284ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:05.893485Z","caller":"traceutil/trace.go:171","msg":"trace[1151887660] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"221.674779ms","start":"2026-05-13T00:57:05.671787Z","end":"2026-05-13T00:57:05.893461Z","steps":["trace[1151887660] 'process raft request' (duration: 221.518916ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:11.008618Z","caller":"traceutil/trace.go:171","msg":"trace[730119354] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"105.938818ms","start":"2026-05-13T00:57:10.902652Z","end":"2026-05-13T00:57:11.008591Z","steps":["trace[730119354] 'process raft request' (duration: 105.650173ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:11.633051Z","caller":"traceutil/trace.go:171","msg":"trace[1913223779] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"253.55843ms","start":"2026-05-13T00:57:11.379467Z","end":"2026-05-13T00:57:11.633026Z","steps":["trace[1913223779] 'process raft request' (duration: 253.394797ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:11.855377Z","caller":"traceutil/trace.go:171","msg":"trace[382044269] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"214.527623ms","start":"2026-05-13T00:57:11.640824Z","end":"2026-05-13T00:57:11.855351Z","steps":["trace[382044269] 'process raft request' (duration: 214.36432ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:11.863958Z","caller":"traceutil/trace.go:171","msg":"trace[1613970717] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:894; }","duration":"135.388739ms","start":"2026-05-13T00:57:11.728556Z","end":"2026-05-13T00:57:11.863944Z","steps":["trace[1613970717] 'read index received' (duration: 126.820891ms)","trace[1613970717] 'applied index is now lower than readState.Index' (duration: 8.567288ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:11.864003Z","caller":"traceutil/trace.go:171","msg":"trace[1399511890] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"219.808487ms","start":"2026-05-13T00:57:11.644173Z","end":"2026-05-13T00:57:11.863982Z","steps":["trace[1399511890] 'process raft request' (duration: 219.648354ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:11.864143Z","caller":"traceutil/trace.go:171","msg":"trace[1796362982] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"218.932889ms","start":"2026-05-13T00:57:11.645203Z","end":"2026-05-13T00:57:11.864136Z","steps":["trace[1796362982] 'process raft request' (duration: 218.706595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:11.864259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.688104ms","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-05-13T00:57:11.864283Z","caller":"traceutil/trace.go:171","msg":"trace[111515984] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:866; }","duration":"135.768726ms","start":"2026-05-13T00:57:11.728506Z","end":"2026-05-13T00:57:11.864275Z","steps":["trace[111515984] 'agreement among raft nodes before linearized reading' (duration: 135.715705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:11.947619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.012129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-13T00:57:11.94771Z","caller":"traceutil/trace.go:171","msg":"trace[611922421] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:867; }","duration":"117.148132ms","start":"2026-05-13T00:57:11.830535Z","end":"2026-05-13T00:57:11.947683Z","steps":["trace[611922421] 'agreement among raft nodes before linearized reading' (duration: 116.920598ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:12.274077Z","caller":"traceutil/trace.go:171","msg":"trace[1152687592] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"191.325474ms","start":"2026-05-13T00:57:12.082724Z","end":"2026-05-13T00:57:12.274049Z","steps":["trace[1152687592] 'read index received' (duration: 191.176092ms)","trace[1152687592] 'applied index is now lower than readState.Index' (duration: 148.512µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:12.274211Z","caller":"traceutil/trace.go:171","msg":"trace[2069672604] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"216.679157ms","start":"2026-05-13T00:57:12.05752Z","end":"2026-05-13T00:57:12.274199Z","steps":["trace[2069672604] 'process raft request' (duration: 216.389012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.27455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.804724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-13T00:57:12.274619Z","caller":"traceutil/trace.go:171","msg":"trace[1590559279] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:871; }","duration":"191.903316ms","start":"2026-05-13T00:57:12.082703Z","end":"2026-05-13T00:57:12.274607Z","steps":["trace[1590559279] 'agreement among raft nodes before linearized reading' (duration: 191.771632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.275125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.300473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-13T00:57:12.275165Z","caller":"traceutil/trace.go:171","msg":"trace[1295707444] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:871; }","duration":"192.342794ms","start":"2026-05-13T00:57:12.082812Z","end":"2026-05-13T00:57:12.275155Z","steps":["trace[1295707444] 'agreement among raft nodes before linearized reading' (duration: 192.259702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.275201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.570824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:57:12.275545Z","caller":"traceutil/trace.go:171","msg":"trace[1018134385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"105.961642ms","start":"2026-05-13T00:57:12.169571Z","end":"2026-05-13T00:57:12.275533Z","steps":["trace[1018134385] 'agreement among raft nodes before linearized reading' (duration: 105.522574ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:12.577207Z","caller":"traceutil/trace.go:171","msg":"trace[819492643] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"177.673839ms","start":"2026-05-13T00:57:12.39951Z","end":"2026-05-13T00:57:12.577184Z","steps":["trace[819492643] 'read index received' (duration: 177.542266ms)","trace[819492643] 'applied index is now lower than readState.Index' (duration: 130.573µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:12.577464Z","caller":"traceutil/trace.go:171","msg":"trace[907431255] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"207.811906ms","start":"2026-05-13T00:57:12.369641Z","end":"2026-05-13T00:57:12.577453Z","steps":["trace[907431255] 'process raft request' (duration: 207.375138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.577785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.262319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-13T00:57:12.577825Z","caller":"traceutil/trace.go:171","msg":"trace[1701883584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"178.341612ms","start":"2026-05-13T00:57:12.399474Z","end":"2026-05-13T00:57:12.577816Z","steps":["trace[1701883584] 'agreement among raft nodes before linearized reading' (duration: 178.189329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.578024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.160758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T00:57:12.578096Z","caller":"traceutil/trace.go:171","msg":"trace[787097845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:872; }","duration":"177.270351ms","start":"2026-05-13T00:57:12.400809Z","end":"2026-05-13T00:57:12.578079Z","steps":["trace[787097845] 'agreement among raft nodes before linearized reading' (duration: 177.001895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.57829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.620976ms","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-05-13T00:57:12.578329Z","caller":"traceutil/trace.go:171","msg":"trace[1944270911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:872; }","duration":"123.683227ms","start":"2026-05-13T00:57:12.454636Z","end":"2026-05-13T00:57:12.57832Z","steps":["trace[1944270911] 'agreement among raft nodes before linearized reading' (duration: 123.585115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:12.578442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.610855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T00:57:12.57851Z","caller":"traceutil/trace.go:171","msg":"trace[1801184663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:872; }","duration":"124.680396ms","start":"2026-05-13T00:57:12.453819Z","end":"2026-05-13T00:57:12.5785Z","steps":["trace[1801184663] 'agreement among raft nodes before linearized reading' (duration: 124.511182ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:12.728148Z","caller":"traceutil/trace.go:171","msg":"trace[434517706] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"145.997591ms","start":"2026-05-13T00:57:12.582133Z","end":"2026-05-13T00:57:12.72813Z","steps":["trace[434517706] 'process raft request' (duration: 87.084165ms)","trace[434517706] 'compare' (duration: 58.613261ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:12.728271Z","caller":"traceutil/trace.go:171","msg":"trace[1565029612] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"145.124416ms","start":"2026-05-13T00:57:12.583124Z","end":"2026-05-13T00:57:12.728248Z","steps":["trace[1565029612] 'process raft request' (duration: 145.066665ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:12.728494Z","caller":"traceutil/trace.go:171","msg":"trace[1649567762] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"146.172036ms","start":"2026-05-13T00:57:12.582313Z","end":"2026-05-13T00:57:12.728485Z","steps":["trace[1649567762] 'process raft request' (duration: 145.765507ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:13.435503Z","caller":"traceutil/trace.go:171","msg":"trace[1115747401] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"113.371848ms","start":"2026-05-13T00:57:13.322106Z","end":"2026-05-13T00:57:13.435477Z","steps":["trace[1115747401] 'process raft request' (duration: 107.814261ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:39.798179Z","caller":"traceutil/trace.go:171","msg":"trace[89830153] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"217.248281ms","start":"2026-05-13T00:57:39.580886Z","end":"2026-05-13T00:57:39.798134Z","steps":["trace[89830153] 'process raft request' (duration: 217.109689ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:39.999806Z","caller":"traceutil/trace.go:171","msg":"trace[1572294] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"151.40359ms","start":"2026-05-13T00:57:39.848367Z","end":"2026-05-13T00:57:39.999771Z","steps":["trace[1572294] 'process raft request' (duration: 151.167167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:40.461905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.297826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:57:40.461999Z","caller":"traceutil/trace.go:171","msg":"trace[1426752794] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1058; }","duration":"233.45284ms","start":"2026-05-13T00:57:40.228524Z","end":"2026-05-13T00:57:40.461976Z","steps":["trace[1426752794] 'count revisions from in-memory index tree' (duration: 233.184894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:40.462103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.25684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:57:40.462194Z","caller":"traceutil/trace.go:171","msg":"trace[404606698] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1058; }","duration":"277.741558ms","start":"2026-05-13T00:57:40.184434Z","end":"2026-05-13T00:57:40.462175Z","steps":["trace[404606698] 'count revisions from in-memory index tree' (duration: 277.105918ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:41.025282Z","caller":"traceutil/trace.go:171","msg":"trace[2101677238] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"145.983266ms","start":"2026-05-13T00:57:40.87926Z","end":"2026-05-13T00:57:41.025243Z","steps":["trace[2101677238] 'process raft request' (duration: 129.315549ms)","trace[2101677238] 'compare' (duration: 16.420054ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:55.537573Z","caller":"traceutil/trace.go:171","msg":"trace[1796979563] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"380.758191ms","start":"2026-05-13T00:57:55.156791Z","end":"2026-05-13T00:57:55.537549Z","steps":["trace[1796979563] 'read index received' (duration: 380.67705ms)","trace[1796979563] 'applied index is now lower than readState.Index' (duration: 79.651µs)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:57:55.537953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.126856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-13T00:57:55.538057Z","caller":"traceutil/trace.go:171","msg":"trace[1445619907] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1130; }","duration":"381.278039ms","start":"2026-05-13T00:57:55.156759Z","end":"2026-05-13T00:57:55.538037Z","steps":["trace[1445619907] 'agreement among raft nodes before linearized reading' (duration: 380.911884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:55.538111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.156746Z","time spent":"381.35008ms","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":28,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-13T00:57:55.538477Z","caller":"traceutil/trace.go:171","msg":"trace[206112928] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"448.043337ms","start":"2026-05-13T00:57:55.090416Z","end":"2026-05-13T00:57:55.53846Z","steps":["trace[206112928] 'process raft request' (duration: 446.983403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:55.538575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.090396Z","time spent":"448.121298ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T00:57:56.061086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.312217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-13T00:57:56.061206Z","caller":"traceutil/trace.go:171","msg":"trace[563877748] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"523.522764ms","start":"2026-05-13T00:57:55.537668Z","end":"2026-05-13T00:57:56.061191Z","steps":["trace[563877748] 'read index received' (duration: 323.957743ms)","trace[563877748] 'applied index is now lower than readState.Index' (duration: 199.563751ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:57:56.061308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.912168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:57:56.061354Z","caller":"traceutil/trace.go:171","msg":"trace[1474364183] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1131; }","duration":"758.001639ms","start":"2026-05-13T00:57:55.30334Z","end":"2026-05-13T00:57:56.061342Z","steps":["trace[1474364183] 'agreement among raft nodes before linearized reading' (duration: 757.897398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:56.061361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.399179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-13T00:57:56.061387Z","caller":"traceutil/trace.go:171","msg":"trace[1882165025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"865.4512ms","start":"2026-05-13T00:57:55.195928Z","end":"2026-05-13T00:57:56.06138Z","steps":["trace[1882165025] 'agreement among raft nodes before linearized reading' (duration: 865.349048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:56.06139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.30328Z","time spent":"758.100712ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":26,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:57:56.061414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.195916Z","time spent":"865.492301ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-13T00:57:56.061559Z","caller":"traceutil/trace.go:171","msg":"trace[1813373609] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"891.227852ms","start":"2026-05-13T00:57:55.170316Z","end":"2026-05-13T00:57:56.061544Z","steps":["trace[1813373609] 'process raft request' (duration: 691.376057ms)","trace[1813373609] 'compare' (duration: 199.145055ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:57:56.061579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.122173ms","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":"info","ts":"2026-05-13T00:57:56.061609Z","caller":"traceutil/trace.go:171","msg":"trace[1314470241] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1131; }","duration":"322.201634ms","start":"2026-05-13T00:57:55.739397Z","end":"2026-05-13T00:57:56.061599Z","steps":["trace[1314470241] 'agreement among raft nodes before linearized reading' (duration: 322.151314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:56.061628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.170299Z","time spent":"891.292233ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-13T00:57:56.061636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.739377Z","time spent":"322.251865ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:57:56.062084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.110422ms","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":"warn","ts":"2026-05-13T00:57:56.062147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.928448ms","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-05-13T00:57:56.062367Z","caller":"traceutil/trace.go:171","msg":"trace[1012151983] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1131; }","duration":"751.291655ms","start":"2026-05-13T00:57:55.310868Z","end":"2026-05-13T00:57:56.06216Z","steps":["trace[1012151983] 'agreement among raft nodes before linearized reading' (duration: 750.91186ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:56.0624Z","caller":"traceutil/trace.go:171","msg":"trace[1746972550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1131; }","duration":"891.280294ms","start":"2026-05-13T00:57:55.1711Z","end":"2026-05-13T00:57:56.06238Z","steps":["trace[1746972550] 'agreement among raft nodes before linearized reading' (duration: 890.873477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:56.062382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.822681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-13T00:57:56.06243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.310839Z","time spent":"751.576279ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":6,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-13T00:57:56.062444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.171062Z","time spent":"891.374525ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-13T00:57:56.062475Z","caller":"traceutil/trace.go:171","msg":"trace[597621670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1131; }","duration":"892.746984ms","start":"2026-05-13T00:57:55.16971Z","end":"2026-05-13T00:57:56.062457Z","steps":["trace[597621670] 'agreement among raft nodes before linearized reading' (duration: 891.75138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:56.062524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:55.169686Z","time spent":"892.825846ms","remote":"127.0.0.1:41644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-13T00:57:56.995461Z","caller":"traceutil/trace.go:171","msg":"trace[283578872] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"120.878728ms","start":"2026-05-13T00:57:56.874559Z","end":"2026-05-13T00:57:56.995438Z","steps":["trace[283578872] 'process raft request' (duration: 120.732956ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:57:57.379153Z","caller":"traceutil/trace.go:171","msg":"trace[1322586275] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"209.704061ms","start":"2026-05-13T00:57:57.169423Z","end":"2026-05-13T00:57:57.379127Z","steps":["trace[1322586275] 'read index received' (duration: 185.934081ms)","trace[1322586275] 'applied index is now lower than readState.Index' (duration: 23.76896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:57:57.379276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.837233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-13T00:57:57.379276Z","caller":"traceutil/trace.go:171","msg":"trace[120987723] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"307.305056ms","start":"2026-05-13T00:57:57.071939Z","end":"2026-05-13T00:57:57.379244Z","steps":["trace[120987723] 'process raft request' (duration: 283.416474ms)","trace[120987723] 'compare' (duration: 23.656979ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:57:57.379307Z","caller":"traceutil/trace.go:171","msg":"trace[181112764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"209.928935ms","start":"2026-05-13T00:57:57.169368Z","end":"2026-05-13T00:57:57.379297Z","steps":["trace[181112764] 'agreement among raft nodes before linearized reading' (duration: 209.850734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:57:57.379481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-13T00:57:57.071917Z","time spent":"307.421777ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-13T00:57:58.168232Z","caller":"traceutil/trace.go:171","msg":"trace[1523926594] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"101.476871ms","start":"2026-05-13T00:57:58.066718Z","end":"2026-05-13T00:57:58.168195Z","steps":["trace[1523926594] 'process raft request' (duration: 100.976593ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:03.290349Z","caller":"traceutil/trace.go:171","msg":"trace[1189699582] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"102.484273ms","start":"2026-05-13T00:58:03.187846Z","end":"2026-05-13T00:58:03.290331Z","steps":["trace[1189699582] 'read index received' (duration: 102.309351ms)","trace[1189699582] 'applied index is now lower than readState.Index' (duration: 174.102µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:58:03.290402Z","caller":"traceutil/trace.go:171","msg":"trace[35582048] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"118.444697ms","start":"2026-05-13T00:58:03.171939Z","end":"2026-05-13T00:58:03.290383Z","steps":["trace[35582048] 'process raft request' (duration: 118.274985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:03.29048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.625595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-05-13T00:58:03.290511Z","caller":"traceutil/trace.go:171","msg":"trace[636762658] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1160; }","duration":"102.700285ms","start":"2026-05-13T00:58:03.187802Z","end":"2026-05-13T00:58:03.290502Z","steps":["trace[636762658] 'agreement among raft nodes before linearized reading' (duration: 102.628794ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:16.486502Z","caller":"traceutil/trace.go:171","msg":"trace[1294950833] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"108.95643ms","start":"2026-05-13T00:58:16.377526Z","end":"2026-05-13T00:58:16.486482Z","steps":["trace[1294950833] 'read index received' (duration: 108.814408ms)","trace[1294950833] 'applied index is now lower than readState.Index' (duration: 141.172µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:58:16.486599Z","caller":"traceutil/trace.go:171","msg":"trace[1431893924] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"114.204486ms","start":"2026-05-13T00:58:16.372322Z","end":"2026-05-13T00:58:16.486527Z","steps":["trace[1431893924] 'process raft request' (duration: 114.040575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:16.486732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.211146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-13T00:58:16.486762Z","caller":"traceutil/trace.go:171","msg":"trace[1482824790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"106.271977ms","start":"2026-05-13T00:58:16.38048Z","end":"2026-05-13T00:58:16.486752Z","steps":["trace[1482824790] 'agreement among raft nodes before linearized reading' (duration: 106.135415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:16.48718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T00:58:16.487254Z","caller":"traceutil/trace.go:171","msg":"trace[1350579375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"109.748561ms","start":"2026-05-13T00:58:16.377486Z","end":"2026-05-13T00:58:16.487234Z","steps":["trace[1350579375] 'agreement among raft nodes before linearized reading' (duration: 109.110782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:17.67855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.907901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"warn","ts":"2026-05-13T00:58:17.678566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.763803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-05-13T00:58:17.678635Z","caller":"traceutil/trace.go:171","msg":"trace[1439064263] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1206; }","duration":"133.017362ms","start":"2026-05-13T00:58:17.545599Z","end":"2026-05-13T00:58:17.678617Z","steps":["trace[1439064263] 'range keys from in-memory index tree' (duration: 132.768569ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:17.678646Z","caller":"traceutil/trace.go:171","msg":"trace[1898951771] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1206; }","duration":"134.889535ms","start":"2026-05-13T00:58:17.543738Z","end":"2026-05-13T00:58:17.678628Z","steps":["trace[1898951771] 'range keys from in-memory index tree' (duration: 134.685643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:24.898406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.395018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"warn","ts":"2026-05-13T00:58:24.898476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.214671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"warn","ts":"2026-05-13T00:58:24.898524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.104048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-13T00:58:24.898553Z","caller":"traceutil/trace.go:171","msg":"trace[2094274040] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1230; }","duration":"124.327643ms","start":"2026-05-13T00:58:24.774204Z","end":"2026-05-13T00:58:24.898531Z","steps":["trace[2094274040] 'range keys from in-memory index tree' (duration: 124.10356ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:24.898502Z","caller":"traceutil/trace.go:171","msg":"trace[1841206899] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1230; }","duration":"131.534709ms","start":"2026-05-13T00:58:24.766949Z","end":"2026-05-13T00:58:24.898484Z","steps":["trace[1841206899] 'range keys from in-memory index tree' (duration: 131.245436ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:24.898591Z","caller":"traceutil/trace.go:171","msg":"trace[520773666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"153.203459ms","start":"2026-05-13T00:58:24.745369Z","end":"2026-05-13T00:58:24.898573Z","steps":["trace[520773666] 'range keys from in-memory index tree' (duration: 152.970566ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:25.987814Z","caller":"traceutil/trace.go:171","msg":"trace[689683934] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"196.426658ms","start":"2026-05-13T00:58:25.791361Z","end":"2026-05-13T00:58:25.987788Z","steps":["trace[689683934] 'process raft request' (duration: 196.266536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:27.000197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.441692ms","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-05-13T00:58:27.000896Z","caller":"traceutil/trace.go:171","msg":"trace[6171002] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1236; }","duration":"125.595415ms","start":"2026-05-13T00:58:26.874677Z","end":"2026-05-13T00:58:27.000272Z","steps":["trace[6171002] 'count revisions from in-memory index tree' (duration: 125.31502ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:27.978406Z","caller":"traceutil/trace.go:171","msg":"trace[1672468146] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"125.910872ms","start":"2026-05-13T00:58:27.852472Z","end":"2026-05-13T00:58:27.978383Z","steps":["trace[1672468146] 'read index received' (duration: 125.76115ms)","trace[1672468146] 'applied index is now lower than readState.Index' (duration: 148.542µs)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:58:27.978475Z","caller":"traceutil/trace.go:171","msg":"trace[1145573911] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"126.6044ms","start":"2026-05-13T00:58:27.851855Z","end":"2026-05-13T00:58:27.978459Z","steps":["trace[1145573911] 'process raft request' (duration: 126.382697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-13T00:58:27.978644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.154594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8j4db\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-13T00:58:27.978709Z","caller":"traceutil/trace.go:171","msg":"trace[1764980964] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8j4db; range_end:; response_count:1; response_revision:1240; }","duration":"126.261695ms","start":"2026-05-13T00:58:27.852432Z","end":"2026-05-13T00:58:27.978694Z","steps":["trace[1764980964] 'agreement among raft nodes before linearized reading' (duration: 126.042773ms)"],"step_count":1} {"level":"info","ts":"2026-05-13T00:58:28.160689Z","caller":"traceutil/trace.go:171","msg":"trace[1218978658] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"109.931136ms","start":"2026-05-13T00:58:28.050733Z","end":"2026-05-13T00:58:28.160664Z","steps":["trace[1218978658] 'read index received' (duration: 57.709181ms)","trace[1218978658] 'applied index is now lower than readState.Index' (duration: 52.220635ms)"],"step_count":2} {"level":"info","ts":"2026-05-13T00:58:28.160777Z","caller":"traceutil/trace.go:171","msg":"trace[851632998] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"166.340602ms","start":"2026-05-13T00:58:27.994396Z","end":"2026-05-13T00:58:28.160736Z","steps":["trace[851632998] 'process raft request' (duration: 114.110116ms)","trace[851632998] 'compare' (duration: 52.051864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-13T00:58:28.160927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.145238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8j4db\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-13T00:58:28.160976Z","caller":"traceutil/trace.go:171","msg":"trace[397468772] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8j4db; range_end:; response_count:1; response_revision:1241; }","duration":"110.26892ms","start":"2026-05-13T00:58:28.050697Z","end":"2026-05-13T00:58:28.160965Z","steps":["trace[397468772] 'agreement among raft nodes before linearized reading' (duration: 110.084638ms)"],"step_count":1}