{"level":"warn","ts":"2026-07-22T14:14:14.842977Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T14:14:14.843051Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-22T14:14:14.843107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T14:14:14.843115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-22T14:14:14.84313Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T14:14:14.843805Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-07-22T14:14:14.843876Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-22T14:14:14.846748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.689386ms"} {"level":"info","ts":"2026-07-22T14:14:14.854588Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-07-22T14:14:14.854664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T14:14:14.854693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-07-22T14:14:14.854711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T14:14:14.854732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-07-22T14:14:14.854773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-07-22T14:14:14.859647Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T14:14:14.866151Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T14:14:14.867999Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-22T14:14:14.8725Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T14:14:14.872674Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T14:14:14.872778Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T14:14:14.872834Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T14:14:14.872842Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T14:14:14.873773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-07-22T14:14:14.874018Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-22T14:14:14.875579Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T14:14:14.875831Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T14:14:14.875896Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T14:14:14.87656Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-22T14:14:14.876588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-22T14:14:15.255993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T14:14:15.256183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T14:14:15.256253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-07-22T14:14:15.256303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-07-22T14:14:15.256351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-22T14:14:15.256399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-07-22T14:14:15.256444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-22T14:14:15.257874Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:14:15.258839Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T14:14:15.258857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T14:14:15.259266Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T14:14:15.259442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T14:14:15.262017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-07-22T14:14:15.25918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T14:14:15.267698Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:14:15.268079Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T14:14:15.268267Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:14:15.268368Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T14:14:16.828838Z","caller":"traceutil/trace.go:171","msg":"trace[1553991802] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"190.056045ms","start":"2026-07-22T14:14:16.638758Z","end":"2026-07-22T14:14:16.828814Z","steps":["trace[1553991802] 'process raft request' (duration: 189.807377ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086649Z","caller":"traceutil/trace.go:171","msg":"trace[1611928709] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"447.102429ms","start":"2026-07-22T14:14:16.639518Z","end":"2026-07-22T14:14:17.086621Z","steps":["trace[1611928709] 'process raft request' (duration: 446.798349ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086698Z","caller":"traceutil/trace.go:171","msg":"trace[245012488] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"368.732595ms","start":"2026-07-22T14:14:16.717921Z","end":"2026-07-22T14:14:17.086654Z","steps":["trace[245012488] 'process raft request' (duration: 368.58013ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086772Z","caller":"traceutil/trace.go:171","msg":"trace[926817443] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"435.137955ms","start":"2026-07-22T14:14:16.651606Z","end":"2026-07-22T14:14:17.086744Z","steps":["trace[926817443] 'read index received' (duration: 177.074879ms)","trace[926817443] 'applied index is now lower than readState.Index' (duration: 258.061666ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:14:17.086857Z","caller":"traceutil/trace.go:171","msg":"trace[1351738736] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"368.286201ms","start":"2026-07-22T14:14:16.71856Z","end":"2026-07-22T14:14:17.086846Z","steps":["trace[1351738736] 'process raft request' (duration: 368.047353ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086847Z","caller":"traceutil/trace.go:171","msg":"trace[124418285] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"368.952183ms","start":"2026-07-22T14:14:16.717848Z","end":"2026-07-22T14:14:17.0868Z","steps":["trace[124418285] 'process raft request' (duration: 368.618642ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086903Z","caller":"traceutil/trace.go:171","msg":"trace[157362168] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"368.87971ms","start":"2026-07-22T14:14:16.718009Z","end":"2026-07-22T14:14:17.086888Z","steps":["trace[157362168] 'process raft request' (duration: 368.517159ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086934Z","caller":"traceutil/trace.go:171","msg":"trace[707244934] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"357.02432ms","start":"2026-07-22T14:14:16.729899Z","end":"2026-07-22T14:14:17.086924Z","steps":["trace[707244934] 'process raft request' (duration: 356.784252ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.08691Z","caller":"traceutil/trace.go:171","msg":"trace[1941703075] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"368.845599ms","start":"2026-07-22T14:14:16.718049Z","end":"2026-07-22T14:14:17.086894Z","steps":["trace[1941703075] 'process raft request' (duration: 368.500418ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.086972Z","caller":"traceutil/trace.go:171","msg":"trace[290082871] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"358.302471ms","start":"2026-07-22T14:14:16.728661Z","end":"2026-07-22T14:14:17.086964Z","steps":["trace[290082871] 'process raft request' (duration: 357.981161ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.087001Z","caller":"traceutil/trace.go:171","msg":"trace[1979006742] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"368.753956ms","start":"2026-07-22T14:14:16.718172Z","end":"2026-07-22T14:14:17.086926Z","steps":["trace[1979006742] 'process raft request' (duration: 368.407085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:14:17.087096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.314907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-22T14:14:17.087144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.990553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-22T14:14:17.087171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.558849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-22T14:14:17.087178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.416951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T14:14:17.087208Z","caller":"traceutil/trace.go:171","msg":"trace[225560749] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"317.487952ms","start":"2026-07-22T14:14:16.769697Z","end":"2026-07-22T14:14:17.087185Z","steps":["trace[225560749] 'agreement among raft nodes before linearized reading' (duration: 317.293796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:14:17.087215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.300164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-22T14:14:17.08722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.243181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T14:14:17.087228Z","caller":"traceutil/trace.go:171","msg":"trace[297285734] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"435.652552ms","start":"2026-07-22T14:14:16.651564Z","end":"2026-07-22T14:14:17.087217Z","steps":["trace[297285734] 'agreement among raft nodes before linearized reading' (duration: 435.434135ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.08722Z","caller":"traceutil/trace.go:171","msg":"trace[2133376701] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"369.115608ms","start":"2026-07-22T14:14:16.718086Z","end":"2026-07-22T14:14:17.087202Z","steps":["trace[2133376701] 'agreement among raft nodes before linearized reading' (duration: 368.932772ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.08726Z","caller":"traceutil/trace.go:171","msg":"trace[1466184250] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"369.376986ms","start":"2026-07-22T14:14:16.717872Z","end":"2026-07-22T14:14:17.087249Z","steps":["trace[1466184250] 'agreement among raft nodes before linearized reading' (duration: 369.309734ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.087268Z","caller":"traceutil/trace.go:171","msg":"trace[1152096322] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"301.344244ms","start":"2026-07-22T14:14:16.78591Z","end":"2026-07-22T14:14:17.087255Z","steps":["trace[1152096322] 'agreement among raft nodes before linearized reading' (duration: 301.234691ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:17.087265Z","caller":"traceutil/trace.go:171","msg":"trace[1529647050] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:0; response_revision:11; }","duration":"371.612178ms","start":"2026-07-22T14:14:16.715628Z","end":"2026-07-22T14:14:17.08724Z","steps":["trace[1529647050] 'agreement among raft nodes before linearized reading' (duration: 371.348109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:14:17.087299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.651555Z","time spent":"435.736224ms","remote":"127.0.0.1:49764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-22T14:14:17.087301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.71784Z","time spent":"369.451258ms","remote":"127.0.0.1:49456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-22T14:14:17.08726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.769673Z","time spent":"317.574425ms","remote":"127.0.0.1:49456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-22T14:14:17.087309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.718077Z","time spent":"369.2166ms","remote":"127.0.0.1:49420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-22T14:14:17.087319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.715605Z","time spent":"371.703931ms","remote":"127.0.0.1:49370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-07-22T14:14:17.087309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.78589Z","time spent":"301.410827ms","remote":"127.0.0.1:49456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-22T14:14:17.087601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.717895Z","time spent":"368.89092ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.087638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.639495Z","time spent":"447.216653ms","remote":"127.0.0.1:49402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:14:17.087754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.718544Z","time spent":"368.335552ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.087857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.71783Z","time spent":"369.066986ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.087954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.717997Z","time spent":"368.925492ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.088132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.729884Z","time spent":"357.071532ms","remote":"127.0.0.1:49538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.088238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.728634Z","time spent":"358.357393ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.088451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.718021Z","time spent":"368.954132ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:17.088621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:16.71816Z","time spent":"368.938472ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:14:52.67394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.094055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:14:52.674048Z","caller":"traceutil/trace.go:171","msg":"trace[1804240168] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:534; }","duration":"194.25294ms","start":"2026-07-22T14:14:52.479772Z","end":"2026-07-22T14:14:52.674025Z","steps":["trace[1804240168] 'range keys from in-memory index tree' (duration: 194.016862ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:56.226468Z","caller":"traceutil/trace.go:171","msg":"trace[1695801946] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"158.645668ms","start":"2026-07-22T14:14:56.067798Z","end":"2026-07-22T14:14:56.226443Z","steps":["trace[1695801946] 'process raft request' (duration: 158.467653ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:14:56.251142Z","caller":"traceutil/trace.go:171","msg":"trace[1876372021] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"181.47164ms","start":"2026-07-22T14:14:56.069642Z","end":"2026-07-22T14:14:56.251113Z","steps":["trace[1876372021] 'process raft request' (duration: 181.242243ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:00.148291Z","caller":"traceutil/trace.go:171","msg":"trace[427850983] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"397.369014ms","start":"2026-07-22T14:14:59.750884Z","end":"2026-07-22T14:15:00.148253Z","steps":["trace[427850983] 'process raft request' (duration: 391.288907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:00.148643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:14:59.750864Z","time spent":"397.526748ms","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:15:00.43277Z","caller":"traceutil/trace.go:171","msg":"trace[936589204] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"215.347029ms","start":"2026-07-22T14:15:00.21739Z","end":"2026-07-22T14:15:00.432737Z","steps":["trace[936589204] 'read index received' (duration: 215.058419ms)","trace[936589204] 'applied index is now lower than readState.Index' (duration: 287.57µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:00.432795Z","caller":"traceutil/trace.go:171","msg":"trace[935661661] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"224.626159ms","start":"2026-07-22T14:15:00.208118Z","end":"2026-07-22T14:15:00.432744Z","steps":["trace[935661661] 'process raft request' (duration: 224.438393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:00.43313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.72509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-84xbv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-22T14:15:00.433172Z","caller":"traceutil/trace.go:171","msg":"trace[1631009723] range","detail":"{range_begin:/registry/pods/kube-system/cilium-84xbv; range_end:; response_count:1; response_revision:643; }","duration":"215.831995ms","start":"2026-07-22T14:15:00.217327Z","end":"2026-07-22T14:15:00.433159Z","steps":["trace[1631009723] 'agreement among raft nodes before linearized reading' (duration: 215.596487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:00.433157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.172235ms","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-07-22T14:15:00.433248Z","caller":"traceutil/trace.go:171","msg":"trace[1538463840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"183.367913ms","start":"2026-07-22T14:15:00.249856Z","end":"2026-07-22T14:15:00.433224Z","steps":["trace[1538463840] 'agreement among raft nodes before linearized reading' (duration: 183.107364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:00.433317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.383955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-22T14:15:00.433422Z","caller":"traceutil/trace.go:171","msg":"trace[433634560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"163.54055ms","start":"2026-07-22T14:15:00.269853Z","end":"2026-07-22T14:15:00.433394Z","steps":["trace[433634560] 'agreement among raft nodes before linearized reading' (duration: 163.151098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:01.708591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956228004072328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T14:15:02.129017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302869209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:15:02.129108Z","caller":"traceutil/trace.go:171","msg":"trace[143099197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"1.303028344s","start":"2026-07-22T14:15:00.82606Z","end":"2026-07-22T14:15:02.129088Z","steps":["trace[143099197] 'range keys from in-memory index tree' (duration: 1.302741576s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:02.129154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:00.826038Z","time spent":"1.303106917s","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T14:15:02.158846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.247753274s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T14:15:02.160258Z","caller":"traceutil/trace.go:171","msg":"trace[911536965] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"951.761073ms","start":"2026-07-22T14:15:01.20847Z","end":"2026-07-22T14:15:02.160231Z","steps":["trace[911536965] 'read index received' (duration: 950.864684ms)","trace[911536965] 'applied index is now lower than readState.Index' (duration: 895.139µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:15:02.160511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.018111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-22T14:15:02.160483Z","caller":"traceutil/trace.go:171","msg":"trace[1925747531] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"953.954554ms","start":"2026-07-22T14:15:01.206428Z","end":"2026-07-22T14:15:02.160383Z","steps":["trace[1925747531] 'process raft request' (duration: 953.740037ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:02.160492Z","caller":"traceutil/trace.go:171","msg":"trace[909603293] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.250892486s","start":"2026-07-22T14:15:00.909457Z","end":"2026-07-22T14:15:02.16035Z","steps":["trace[909603293] 'process raft request' (duration: 1.249940645s)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:02.160561Z","caller":"traceutil/trace.go:171","msg":"trace[1320527284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"952.117964ms","start":"2026-07-22T14:15:01.208428Z","end":"2026-07-22T14:15:02.160546Z","steps":["trace[1320527284] 'agreement among raft nodes before linearized reading' (duration: 951.929398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:02.160598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:01.208418Z","time spent":"952.172166ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T14:15:02.160721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:00.909416Z","time spent":"1.251167884s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:15:02.160926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:01.206399Z","time spent":"954.172251ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:15:03.800011Z","caller":"traceutil/trace.go:171","msg":"trace[1742239652] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"187.623361ms","start":"2026-07-22T14:15:03.612355Z","end":"2026-07-22T14:15:03.799979Z","steps":["trace[1742239652] 'read index received' (duration: 184.632814ms)","trace[1742239652] 'applied index is now lower than readState.Index' (duration: 2.978196ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:15:03.800167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.795086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-22T14:15:03.800234Z","caller":"traceutil/trace.go:171","msg":"trace[1942442930] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:670; }","duration":"187.90866ms","start":"2026-07-22T14:15:03.612307Z","end":"2026-07-22T14:15:03.800215Z","steps":["trace[1942442930] 'agreement among raft nodes before linearized reading' (duration: 187.747885ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:06.600345Z","caller":"traceutil/trace.go:171","msg":"trace[1224359561] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"130.195979ms","start":"2026-07-22T14:15:06.470117Z","end":"2026-07-22T14:15:06.600313Z","steps":["trace[1224359561] 'read index received' (duration: 129.972001ms)","trace[1224359561] 'applied index is now lower than readState.Index' (duration: 222.718µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:06.60041Z","caller":"traceutil/trace.go:171","msg":"trace[448050746] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"164.726959ms","start":"2026-07-22T14:15:06.435646Z","end":"2026-07-22T14:15:06.600373Z","steps":["trace[448050746] 'process raft request' (duration: 164.513812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:06.600621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.955363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-22T14:15:06.600657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.634784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-22T14:15:06.600619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.488679ms","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-07-22T14:15:06.60069Z","caller":"traceutil/trace.go:171","msg":"trace[1831996671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"122.061406ms","start":"2026-07-22T14:15:06.478599Z","end":"2026-07-22T14:15:06.600661Z","steps":["trace[1831996671] 'agreement among raft nodes before linearized reading' (duration: 121.900601ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:06.600755Z","caller":"traceutil/trace.go:171","msg":"trace[1276663593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"118.759398ms","start":"2026-07-22T14:15:06.481978Z","end":"2026-07-22T14:15:06.600737Z","steps":["trace[1276663593] 'agreement among raft nodes before linearized reading' (duration: 118.574382ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:06.600774Z","caller":"traceutil/trace.go:171","msg":"trace[238626194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"130.698535ms","start":"2026-07-22T14:15:06.470062Z","end":"2026-07-22T14:15:06.60076Z","steps":["trace[238626194] 'agreement among raft nodes before linearized reading' (duration: 130.357094ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:14.894106Z","caller":"traceutil/trace.go:171","msg":"trace[1035799393] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"242.058394ms","start":"2026-07-22T14:15:14.652021Z","end":"2026-07-22T14:15:14.894079Z","steps":["trace[1035799393] 'process raft request' (duration: 241.892759ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:14.909591Z","caller":"traceutil/trace.go:171","msg":"trace[1267879983] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"252.872995ms","start":"2026-07-22T14:15:14.65667Z","end":"2026-07-22T14:15:14.909543Z","steps":["trace[1267879983] 'process raft request' (duration: 252.337027ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:14.909871Z","caller":"traceutil/trace.go:171","msg":"trace[1528710057] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"254.745645ms","start":"2026-07-22T14:15:14.6551Z","end":"2026-07-22T14:15:14.909845Z","steps":["trace[1528710057] 'process raft request' (duration: 253.718192ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:18.16495Z","caller":"traceutil/trace.go:171","msg":"trace[823968806] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"257.121822ms","start":"2026-07-22T14:15:17.907797Z","end":"2026-07-22T14:15:18.164919Z","steps":["trace[823968806] 'read index received' (duration: 256.948277ms)","trace[823968806] 'applied index is now lower than readState.Index' (duration: 172.435µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:15:18.165165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.344969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:15:18.165219Z","caller":"traceutil/trace.go:171","msg":"trace[147665658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"257.450843ms","start":"2026-07-22T14:15:17.907755Z","end":"2026-07-22T14:15:18.165206Z","steps":["trace[147665658] 'agreement among raft nodes before linearized reading' (duration: 257.274057ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:18.165558Z","caller":"traceutil/trace.go:171","msg":"trace[985675970] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"821.022126ms","start":"2026-07-22T14:15:17.344504Z","end":"2026-07-22T14:15:18.165526Z","steps":["trace[985675970] 'process raft request' (duration: 820.236931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:18.165708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:17.34447Z","time spent":"821.15642ms","remote":"127.0.0.1:49542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:15:18.396461Z","caller":"traceutil/trace.go:171","msg":"trace[579285461] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"383.783306ms","start":"2026-07-22T14:15:18.01265Z","end":"2026-07-22T14:15:18.396433Z","steps":["trace[579285461] 'process raft request' (duration: 383.649902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:18.396629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:18.012612Z","time spent":"383.937161ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:15:18.39789Z","caller":"traceutil/trace.go:171","msg":"trace[1926550495] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"132.764282ms","start":"2026-07-22T14:15:18.265095Z","end":"2026-07-22T14:15:18.397859Z","steps":["trace[1926550495] 'read index received' (duration: 131.286644ms)","trace[1926550495] 'applied index is now lower than readState.Index' (duration: 1.476737ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:18.39811Z","caller":"traceutil/trace.go:171","msg":"trace[747722360] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"227.577885ms","start":"2026-07-22T14:15:18.170503Z","end":"2026-07-22T14:15:18.398081Z","steps":["trace[747722360] 'process raft request' (duration: 227.210103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:18.398481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.363412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-22T14:15:18.398535Z","caller":"traceutil/trace.go:171","msg":"trace[823513583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"133.473315ms","start":"2026-07-22T14:15:18.265049Z","end":"2026-07-22T14:15:18.398523Z","steps":["trace[823513583] 'agreement among raft nodes before linearized reading' (duration: 133.166875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:54.164786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.053245ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-22T14:15:54.165079Z","caller":"traceutil/trace.go:171","msg":"trace[733475520] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"340.166143ms","start":"2026-07-22T14:15:53.824891Z","end":"2026-07-22T14:15:54.165057Z","steps":["trace[733475520] 'read index received' (duration: 329.657613ms)","trace[733475520] 'applied index is now lower than readState.Index' (duration: 10.50753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:15:54.165174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.276727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:15:54.165202Z","caller":"traceutil/trace.go:171","msg":"trace[2050563135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"340.36596ms","start":"2026-07-22T14:15:53.824827Z","end":"2026-07-22T14:15:54.165193Z","steps":["trace[2050563135] 'agreement among raft nodes before linearized reading' (duration: 340.287247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:54.16523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:53.824799Z","time spent":"340.422882ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T14:15:54.165377Z","caller":"traceutil/trace.go:171","msg":"trace[81680515] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"649.036342ms","start":"2026-07-22T14:15:53.516329Z","end":"2026-07-22T14:15:54.165365Z","steps":["trace[81680515] 'process raft request' (duration: 648.587598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:54.165457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:53.516297Z","time spent":"649.103355ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:15:55.076099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956228004073130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T14:15:55.5766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956228004073130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T14:15:55.749771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.1751294s","expected-duration":"1s"} {"level":"info","ts":"2026-07-22T14:15:55.750508Z","caller":"traceutil/trace.go:171","msg":"trace[1685320730] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"1.175395369s","start":"2026-07-22T14:15:54.575076Z","end":"2026-07-22T14:15:55.750472Z","steps":["trace[1685320730] 'read index received' (duration: 1.174893743s)","trace[1685320730] 'applied index is now lower than readState.Index' (duration: 499.866µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:15:55.75062Z","caller":"traceutil/trace.go:171","msg":"trace[2048083350] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.176061241s","start":"2026-07-22T14:15:54.574517Z","end":"2026-07-22T14:15:55.750578Z","steps":["trace[2048083350] 'process raft request' (duration: 1.175527963s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:55.750815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:54.574489Z","time spent":"1.176221976s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:15:55.750818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175706619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:15:55.750876Z","caller":"traceutil/trace.go:171","msg":"trace[858142762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"1.175873365s","start":"2026-07-22T14:15:54.574988Z","end":"2026-07-22T14:15:55.750862Z","steps":["trace[858142762] 'agreement among raft nodes before linearized reading' (duration: 1.175624717s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:55.750903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.324766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-22T14:15:55.750923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.440321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-22T14:15:55.75094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:54.574963Z","time spent":"1.175966297s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-22T14:15:55.750989Z","caller":"traceutil/trace.go:171","msg":"trace[1865744888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"626.481761ms","start":"2026-07-22T14:15:55.124482Z","end":"2026-07-22T14:15:55.750964Z","steps":["trace[1865744888] 'agreement among raft nodes before linearized reading' (duration: 626.252994ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:15:55.751023Z","caller":"traceutil/trace.go:171","msg":"trace[1685276541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"626.603906ms","start":"2026-07-22T14:15:55.124385Z","end":"2026-07-22T14:15:55.750989Z","steps":["trace[1685276541] 'agreement among raft nodes before linearized reading' (duration: 626.304836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:55.751074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:55.124354Z","time spent":"626.711469ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T14:15:55.751034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:55.124418Z","time spent":"626.607076ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:15:55.750907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.036485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-22T14:15:55.751235Z","caller":"traceutil/trace.go:171","msg":"trace[762438548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"646.431509ms","start":"2026-07-22T14:15:55.104794Z","end":"2026-07-22T14:15:55.751226Z","steps":["trace[762438548] 'agreement among raft nodes before linearized reading' (duration: 646.003255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:55.751265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:55.10477Z","time spent":"646.488051ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T14:15:55.751111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.030207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:15:55.751426Z","caller":"traceutil/trace.go:171","msg":"trace[2080623061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"925.387048ms","start":"2026-07-22T14:15:54.82603Z","end":"2026-07-22T14:15:55.751417Z","steps":["trace[2080623061] 'agreement among raft nodes before linearized reading' (duration: 924.642184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:15:55.751447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:15:54.826005Z","time spent":"925.43706ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T14:16:22.436944Z","caller":"traceutil/trace.go:171","msg":"trace[1668417703] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1162; }","duration":"514.310088ms","start":"2026-07-22T14:16:21.922606Z","end":"2026-07-22T14:16:22.436916Z","steps":["trace[1668417703] 'read index received' (duration: 40.118871ms)","trace[1668417703] 'applied index is now lower than readState.Index' (duration: 474.189797ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:22.437195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.569006ms","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-07-22T14:16:22.437237Z","caller":"traceutil/trace.go:171","msg":"trace[822353119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"514.657729ms","start":"2026-07-22T14:16:21.922568Z","end":"2026-07-22T14:16:22.437226Z","steps":["trace[822353119] 'agreement among raft nodes before linearized reading' (duration: 514.496414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.437276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.922555Z","time spent":"514.713191ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T14:16:22.437095Z","caller":"traceutil/trace.go:171","msg":"trace[769406350] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"525.961925ms","start":"2026-07-22T14:16:21.911063Z","end":"2026-07-22T14:16:22.437025Z","steps":["trace[769406350] 'process raft request' (duration: 525.684136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.437455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.911048Z","time spent":"526.324698ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:22.439324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.373197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:16:22.439408Z","caller":"traceutil/trace.go:171","msg":"trace[187026815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1124; }","duration":"513.498341ms","start":"2026-07-22T14:16:21.925892Z","end":"2026-07-22T14:16:22.439391Z","steps":["trace[187026815] 'agreement among raft nodes before linearized reading' (duration: 513.282664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.439455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.925878Z","time spent":"513.568024ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-22T14:16:22.439447Z","caller":"traceutil/trace.go:171","msg":"trace[304667340] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"169.826727ms","start":"2026-07-22T14:16:22.26959Z","end":"2026-07-22T14:16:22.439417Z","steps":["trace[304667340] 'process raft request' (duration: 169.451545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.4396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.475741ms","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-07-22T14:16:22.439679Z","caller":"traceutil/trace.go:171","msg":"trace[1151428928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"509.590215ms","start":"2026-07-22T14:16:21.93007Z","end":"2026-07-22T14:16:22.43966Z","steps":["trace[1151428928] 'agreement among raft nodes before linearized reading' (duration: 509.379628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:22.439719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:21.930056Z","time spent":"509.655476ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T14:16:24.362019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.786057ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-22T14:16:24.362271Z","caller":"traceutil/trace.go:171","msg":"trace[319402566] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1168; }","duration":"395.010199ms","start":"2026-07-22T14:16:23.967243Z","end":"2026-07-22T14:16:24.362253Z","steps":["trace[319402566] 'read index received' (duration: 207.993244ms)","trace[319402566] 'applied index is now lower than readState.Index' (duration: 187.016065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:24.362421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.178175ms","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-07-22T14:16:24.362455Z","caller":"traceutil/trace.go:171","msg":"trace[1701775009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1128; }","duration":"395.258907ms","start":"2026-07-22T14:16:23.967186Z","end":"2026-07-22T14:16:24.362445Z","steps":["trace[1701775009] 'agreement among raft nodes before linearized reading' (duration: 395.133243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:24.362486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:23.967165Z","time spent":"395.312399ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T14:16:24.363021Z","caller":"traceutil/trace.go:171","msg":"trace[1821076510] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"517.396718ms","start":"2026-07-22T14:16:23.84504Z","end":"2026-07-22T14:16:24.362437Z","steps":["trace[1821076510] 'process raft request' (duration: 517.083988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:24.363261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:23.845012Z","time spent":"518.147152ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:16:24.490743Z","caller":"traceutil/trace.go:171","msg":"trace[451068781] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"123.057711ms","start":"2026-07-22T14:16:24.367631Z","end":"2026-07-22T14:16:24.490689Z","steps":["trace[451068781] 'process raft request' (duration: 122.791272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:24.744782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.402689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:16:24.744951Z","caller":"traceutil/trace.go:171","msg":"trace[341587448] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"209.634298ms","start":"2026-07-22T14:16:24.535299Z","end":"2026-07-22T14:16:24.744933Z","steps":["trace[341587448] 'process raft request' (duration: 209.581827ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:24.74497Z","caller":"traceutil/trace.go:171","msg":"trace[1538763369] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"214.507076ms","start":"2026-07-22T14:16:24.530445Z","end":"2026-07-22T14:16:24.744952Z","steps":["trace[1538763369] 'read index received' (duration: 83.845499ms)","trace[1538763369] 'applied index is now lower than readState.Index' (duration: 130.660297ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:24.744962Z","caller":"traceutil/trace.go:171","msg":"trace[1925633267] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"248.978474ms","start":"2026-07-22T14:16:24.49593Z","end":"2026-07-22T14:16:24.744908Z","steps":["trace[1925633267] 'process raft request' (duration: 118.343787ms)","trace[1925633267] 'compare' (duration: 130.293645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:24.745356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.554128ms","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-07-22T14:16:24.745454Z","caller":"traceutil/trace.go:171","msg":"trace[1543014205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1132; }","duration":"243.686562ms","start":"2026-07-22T14:16:24.501744Z","end":"2026-07-22T14:16:24.745431Z","steps":["trace[1543014205] 'agreement among raft nodes before linearized reading' (duration: 243.415514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:24.745533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.223679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:16:24.745677Z","caller":"traceutil/trace.go:171","msg":"trace[362757793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1132; }","duration":"244.357924ms","start":"2026-07-22T14:16:24.501268Z","end":"2026-07-22T14:16:24.745626Z","steps":["trace[362757793] 'agreement among raft nodes before linearized reading' (duration: 243.905939ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:26.874876Z","caller":"traceutil/trace.go:171","msg":"trace[1459443299] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"374.37656ms","start":"2026-07-22T14:16:26.500413Z","end":"2026-07-22T14:16:26.87479Z","steps":["trace[1459443299] 'process raft request' (duration: 374.196924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:26.875042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:26.500385Z","time spent":"374.565886ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:16:27.054724Z","caller":"traceutil/trace.go:171","msg":"trace[1316626267] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1176; }","duration":"305.568988ms","start":"2026-07-22T14:16:26.749126Z","end":"2026-07-22T14:16:27.054695Z","steps":["trace[1316626267] 'read index received' (duration: 125.48813ms)","trace[1316626267] 'applied index is now lower than readState.Index' (duration: 180.079668ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:27.054891Z","caller":"traceutil/trace.go:171","msg":"trace[1177114331] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"518.332437ms","start":"2026-07-22T14:16:26.536517Z","end":"2026-07-22T14:16:27.054849Z","steps":["trace[1177114331] 'process raft request' (duration: 448.69935ms)","trace[1177114331] 'compare' (duration: 69.373829ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:27.055028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:26.536475Z","time spent":"518.477542ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:27.055426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.725973ms","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-07-22T14:16:27.05546Z","caller":"traceutil/trace.go:171","msg":"trace[483366676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1136; }","duration":"305.802386ms","start":"2026-07-22T14:16:26.74965Z","end":"2026-07-22T14:16:27.055452Z","steps":["trace[483366676] 'agreement among raft nodes before linearized reading' (duration: 305.667062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:27.055458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.187948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-22T14:16:27.055481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:26.749629Z","time spent":"305.846707ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:16:27.05546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.226213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:16:27.055569Z","caller":"traceutil/trace.go:171","msg":"trace[122101282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"229.410088ms","start":"2026-07-22T14:16:26.826136Z","end":"2026-07-22T14:16:27.055546Z","steps":["trace[122101282] 'agreement among raft nodes before linearized reading' (duration: 229.186921ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:27.055595Z","caller":"traceutil/trace.go:171","msg":"trace[100278575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1136; }","duration":"306.411605ms","start":"2026-07-22T14:16:26.749082Z","end":"2026-07-22T14:16:27.055494Z","steps":["trace[100278575] 'agreement among raft nodes before linearized reading' (duration: 305.820866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:27.055725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:26.749061Z","time spent":"306.573561ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T14:16:27.056001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.722584ms","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-07-22T14:16:27.05612Z","caller":"traceutil/trace.go:171","msg":"trace[1620197655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1136; }","duration":"252.937571ms","start":"2026-07-22T14:16:26.803156Z","end":"2026-07-22T14:16:27.056094Z","steps":["trace[1620197655] 'agreement among raft nodes before linearized reading' (duration: 252.223148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:27.056435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.534993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:16:27.056495Z","caller":"traceutil/trace.go:171","msg":"trace[496299583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1136; }","duration":"213.645377ms","start":"2026-07-22T14:16:26.842834Z","end":"2026-07-22T14:16:27.056479Z","steps":["trace[496299583] 'agreement among raft nodes before linearized reading' (duration: 212.569092ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:27.215094Z","caller":"traceutil/trace.go:171","msg":"trace[263273392] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"154.644985ms","start":"2026-07-22T14:16:27.060424Z","end":"2026-07-22T14:16:27.215069Z","steps":["trace[263273392] 'process raft request' (duration: 154.567142ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:27.215293Z","caller":"traceutil/trace.go:171","msg":"trace[28697335] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"155.818313ms","start":"2026-07-22T14:16:27.059462Z","end":"2026-07-22T14:16:27.215281Z","steps":["trace[28697335] 'process raft request' (duration: 131.001328ms)","trace[28697335] 'compare' (duration: 24.36099ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:16:27.215511Z","caller":"traceutil/trace.go:171","msg":"trace[540082595] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"153.969502ms","start":"2026-07-22T14:16:27.061518Z","end":"2026-07-22T14:16:27.215488Z","steps":["trace[540082595] 'process raft request' (duration: 153.514518ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:27.215713Z","caller":"traceutil/trace.go:171","msg":"trace[1060364422] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"153.26244ms","start":"2026-07-22T14:16:27.062427Z","end":"2026-07-22T14:16:27.21569Z","steps":["trace[1060364422] 'process raft request' (duration: 152.984621ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:16:43.635612Z","caller":"traceutil/trace.go:171","msg":"trace[1431683625] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"675.33738ms","start":"2026-07-22T14:16:42.960249Z","end":"2026-07-22T14:16:43.635586Z","steps":["trace[1431683625] 'process raft request' (duration: 675.178275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:43.635769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:42.960235Z","time spent":"675.450084ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:44.424174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.634495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:16:44.424609Z","caller":"traceutil/trace.go:171","msg":"trace[1686097932] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.293170185s","start":"2026-07-22T14:16:43.1314Z","end":"2026-07-22T14:16:44.42457Z","steps":["trace[1686097932] 'process raft request' (duration: 692.982882ms)","trace[1686097932] 'compare' (duration: 598.908901ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:44.424755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.131372Z","time spent":"1.293286028s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:16:44.429065Z","caller":"traceutil/trace.go:171","msg":"trace[1686341788] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"1.14040987s","start":"2026-07-22T14:16:43.288628Z","end":"2026-07-22T14:16:44.429038Z","steps":["trace[1686341788] 'read index received' (duration: 347.678344ms)","trace[1686341788] 'applied index is now lower than readState.Index' (duration: 792.729486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:44.429209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140575696s","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":"info","ts":"2026-07-22T14:16:44.42927Z","caller":"traceutil/trace.go:171","msg":"trace[1507755521] 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:1240; }","duration":"1.14069323s","start":"2026-07-22T14:16:43.288561Z","end":"2026-07-22T14:16:44.429254Z","steps":["trace[1507755521] 'agreement among raft nodes before linearized reading' (duration: 1.140571116s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.429276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010655493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:16:44.429335Z","caller":"traceutil/trace.go:171","msg":"trace[731624081] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1240; }","duration":"1.010760627s","start":"2026-07-22T14:16:43.418561Z","end":"2026-07-22T14:16:44.429321Z","steps":["trace[731624081] 'agreement among raft nodes before linearized reading' (duration: 1.010652243s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.429369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.418536Z","time spent":"1.010823749s","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:16:44.429365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09907086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-22T14:16:44.429406Z","caller":"traceutil/trace.go:171","msg":"trace[458642535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"1.099163723s","start":"2026-07-22T14:16:43.330233Z","end":"2026-07-22T14:16:44.429396Z","steps":["trace[458642535] 'agreement among raft nodes before linearized reading' (duration: 1.098962577s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.429438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.330213Z","time spent":"1.099218235s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T14:16:44.429299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.288541Z","time spent":"1.140752462s","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"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-07-22T14:16:44.42961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.406732ms","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-07-22T14:16:44.429674Z","caller":"traceutil/trace.go:171","msg":"trace[708621612] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1240; }","duration":"543.571367ms","start":"2026-07-22T14:16:43.886092Z","end":"2026-07-22T14:16:44.429664Z","steps":["trace[708621612] 'agreement among raft nodes before linearized reading' (duration: 543.428202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.429784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.886072Z","time spent":"543.628059ms","remote":"127.0.0.1:37726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:16:44.429843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035651633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-22T14:16:44.429824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035660444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-22T14:16:44.429679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035778128s","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-07-22T14:16:44.429872Z","caller":"traceutil/trace.go:171","msg":"trace[505242522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"1.035719536s","start":"2026-07-22T14:16:43.394146Z","end":"2026-07-22T14:16:44.429866Z","steps":["trace[505242522] 'agreement among raft nodes before linearized reading' (duration: 1.035614093s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.429894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.394123Z","time spent":"1.035767457s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-22T14:16:44.4299Z","caller":"traceutil/trace.go:171","msg":"trace[1992128590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"1.036046246s","start":"2026-07-22T14:16:43.393841Z","end":"2026-07-22T14:16:44.429887Z","steps":["trace[1992128590] 'agreement among raft nodes before linearized reading' (duration: 1.035781087s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.429933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.393776Z","time spent":"1.03614956s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T14:16:44.430012Z","caller":"traceutil/trace.go:171","msg":"trace[436954707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"1.035774198s","start":"2026-07-22T14:16:43.394107Z","end":"2026-07-22T14:16:44.429881Z","steps":["trace[436954707] 'agreement among raft nodes before linearized reading' (duration: 1.035477758s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:16:44.430084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:43.394102Z","time spent":"1.035960304s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:16:59.250212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.850185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:16:59.250285Z","caller":"traceutil/trace.go:171","msg":"trace[1862749459] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"734.926091ms","start":"2026-07-22T14:16:58.515346Z","end":"2026-07-22T14:16:59.250272Z","steps":["trace[1862749459] 'process raft request' (duration: 198.951042ms)","trace[1862749459] 'compare' (duration: 535.779443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:16:59.250316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:58.515328Z","time spent":"734.974232ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:16:59.839628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125237978s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-22T14:17:00.088194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.168157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:17:00.088379Z","caller":"traceutil/trace.go:171","msg":"trace[8617351] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1343; }","duration":"1.567827681s","start":"2026-07-22T14:16:58.520535Z","end":"2026-07-22T14:17:00.088363Z","steps":["trace[8617351] 'read index received' (duration: 193.894078ms)","trace[8617351] 'applied index is now lower than readState.Index' (duration: 1.373932573s)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:00.088478Z","caller":"traceutil/trace.go:171","msg":"trace[215257562] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.572571764s","start":"2026-07-22T14:16:58.515897Z","end":"2026-07-22T14:17:00.088469Z","steps":["trace[215257562] 'process raft request' (duration: 1.572386968s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:00.088533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:58.515887Z","time spent":"1.572614315s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:17:00.08856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353995436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T14:17:00.088642Z","caller":"traceutil/trace.go:171","msg":"trace[1994948681] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1295; }","duration":"1.35413011s","start":"2026-07-22T14:16:58.734492Z","end":"2026-07-22T14:17:00.088622Z","steps":["trace[1994948681] 'agreement among raft nodes before linearized reading' (duration: 1.353984925s)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:00.088648Z","caller":"traceutil/trace.go:171","msg":"trace[1037645735] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.572752699s","start":"2026-07-22T14:16:58.515884Z","end":"2026-07-22T14:17:00.088637Z","steps":["trace[1037645735] 'process raft request' (duration: 1.324053985s)","trace[1037645735] 'compare' (duration: 247.876108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:17:00.088701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:58.515873Z","time spent":"1.572801752s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:17:00.088688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:58.734475Z","time spent":"1.354202823s","remote":"127.0.0.1:49432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:17:00.088937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.411911ms","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-07-22T14:17:00.088992Z","caller":"traceutil/trace.go:171","msg":"trace[1562121480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"370.512624ms","start":"2026-07-22T14:16:59.718465Z","end":"2026-07-22T14:17:00.088977Z","steps":["trace[1562121480] 'agreement among raft nodes before linearized reading' (duration: 370.3705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:00.08902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:59.718311Z","time spent":"370.702261ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T14:17:00.089101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568564084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:17:00.089135Z","caller":"traceutil/trace.go:171","msg":"trace[288087553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"1.568622436s","start":"2026-07-22T14:16:58.520503Z","end":"2026-07-22T14:17:00.089125Z","steps":["trace[288087553] 'agreement among raft nodes before linearized reading' (duration: 1.568503972s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:00.089146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.043797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-22T14:17:00.089149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.872667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T14:17:00.089165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:58.520493Z","time spent":"1.568665267s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-22T14:17:00.089188Z","caller":"traceutil/trace.go:171","msg":"trace[1950723943] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1295; }","duration":"506.952689ms","start":"2026-07-22T14:16:59.582225Z","end":"2026-07-22T14:17:00.089177Z","steps":["trace[1950723943] 'agreement among raft nodes before linearized reading' (duration: 506.885737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:00.089219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:59.582206Z","time spent":"507.006191ms","remote":"127.0.0.1:49774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:17:00.089248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065658926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:00.089271Z","caller":"traceutil/trace.go:171","msg":"trace[345272130] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1295; }","duration":"1.065705008s","start":"2026-07-22T14:16:59.023558Z","end":"2026-07-22T14:17:00.089263Z","steps":["trace[345272130] 'agreement among raft nodes before linearized reading' (duration: 1.065670427s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:00.089288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:16:59.023543Z","time spent":"1.065740638s","remote":"127.0.0.1:49588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-07-22T14:17:00.089168Z","caller":"traceutil/trace.go:171","msg":"trace[1121825416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"266.095658ms","start":"2026-07-22T14:16:59.823065Z","end":"2026-07-22T14:17:00.089161Z","steps":["trace[1121825416] 'agreement among raft nodes before linearized reading' (duration: 266.062187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:01.418449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.618519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-22T14:17:01.418597Z","caller":"traceutil/trace.go:171","msg":"trace[212295433] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"164.236556ms","start":"2026-07-22T14:17:01.254343Z","end":"2026-07-22T14:17:01.41858Z","steps":["trace[212295433] 'read index received' (duration: 44.252µs)","trace[212295433] 'applied index is now lower than readState.Index' (duration: 164.190144ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:01.418716Z","caller":"traceutil/trace.go:171","msg":"trace[450670726] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"463.402567ms","start":"2026-07-22T14:17:00.955278Z","end":"2026-07-22T14:17:01.41868Z","steps":["trace[450670726] 'process raft request' (duration: 30.472289ms)","trace[450670726] 'compare' (duration: 432.081111ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:17:01.418762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.410302ms","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-07-22T14:17:01.418807Z","caller":"traceutil/trace.go:171","msg":"trace[1975936859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"164.495734ms","start":"2026-07-22T14:17:01.254299Z","end":"2026-07-22T14:17:01.418795Z","steps":["trace[1975936859] 'agreement among raft nodes before linearized reading' (duration: 164.325409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:01.418869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:00.955249Z","time spent":"463.554602ms","remote":"127.0.0.1:49418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T14:17:02.509128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.658654ms","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-07-22T14:17:02.509195Z","caller":"traceutil/trace.go:171","msg":"trace[1181014302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"413.775347ms","start":"2026-07-22T14:17:02.095396Z","end":"2026-07-22T14:17:02.509182Z","steps":["trace[1181014302] 'range keys from in-memory index tree' (duration: 413.52561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.509228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:02.095377Z","time spent":"413.8404ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:17:02.509464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.575976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:02.509584Z","caller":"traceutil/trace.go:171","msg":"trace[1518438312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"683.762363ms","start":"2026-07-22T14:17:01.825801Z","end":"2026-07-22T14:17:02.509564Z","steps":["trace[1518438312] 'range keys from in-memory index tree' (duration: 683.474714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.509613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.906226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:02.509637Z","caller":"traceutil/trace.go:171","msg":"trace[401502015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1303; }","duration":"567.975698ms","start":"2026-07-22T14:17:01.941654Z","end":"2026-07-22T14:17:02.50963Z","steps":["trace[401502015] 'count revisions from in-memory index tree' (duration: 567.793502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.509635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:01.825777Z","time spent":"683.847346ms","remote":"127.0.0.1:49348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T14:17:02.509693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:01.94163Z","time spent":"568.055581ms","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-22T14:17:02.509707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.174653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:17:02.509819Z","caller":"traceutil/trace.go:171","msg":"trace[1384573915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"345.327858ms","start":"2026-07-22T14:17:02.16446Z","end":"2026-07-22T14:17:02.509788Z","steps":["trace[1384573915] 'range keys from in-memory index tree' (duration: 344.980327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.509891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:02.164445Z","time spent":"345.433851ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-22T14:17:02.510128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.460147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-22T14:17:02.510153Z","caller":"traceutil/trace.go:171","msg":"trace[1390531370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"417.509659ms","start":"2026-07-22T14:17:02.092636Z","end":"2026-07-22T14:17:02.510145Z","steps":["trace[1390531370] 'range keys from in-memory index tree' (duration: 417.318843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.510169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:02.09262Z","time spent":"417.54496ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T14:17:02.510668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.812857ms","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-07-22T14:17:02.510721Z","caller":"traceutil/trace.go:171","msg":"trace[1286781082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"330.91249ms","start":"2026-07-22T14:17:02.179796Z","end":"2026-07-22T14:17:02.510708Z","steps":["trace[1286781082] 'range keys from in-memory index tree' (duration: 330.607811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.510749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:02.179773Z","time spent":"330.968982ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T14:17:02.705006Z","caller":"traceutil/trace.go:171","msg":"trace[198682902] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"190.107915ms","start":"2026-07-22T14:17:02.51486Z","end":"2026-07-22T14:17:02.704968Z","steps":["trace[198682902] 'process raft request' (duration: 102.150632ms)","trace[198682902] 'compare' (duration: 87.635802ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:02.705569Z","caller":"traceutil/trace.go:171","msg":"trace[271264400] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"190.657142ms","start":"2026-07-22T14:17:02.514888Z","end":"2026-07-22T14:17:02.705545Z","steps":["trace[271264400] 'process raft request' (duration: 190.028532ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:02.705571Z","caller":"traceutil/trace.go:171","msg":"trace[1394940740] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"190.609271ms","start":"2026-07-22T14:17:02.514939Z","end":"2026-07-22T14:17:02.705549Z","steps":["trace[1394940740] 'process raft request' (duration: 190.352133ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:02.705673Z","caller":"traceutil/trace.go:171","msg":"trace[528496026] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1354; }","duration":"188.908835ms","start":"2026-07-22T14:17:02.516756Z","end":"2026-07-22T14:17:02.705665Z","steps":["trace[528496026] 'read index received' (duration: 83.551789ms)","trace[528496026] 'applied index is now lower than readState.Index' (duration: 105.356236ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:17:02.705786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.008919ms","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-07-22T14:17:02.70581Z","caller":"traceutil/trace.go:171","msg":"trace[283487142] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1308; }","duration":"189.096462ms","start":"2026-07-22T14:17:02.516704Z","end":"2026-07-22T14:17:02.705801Z","steps":["trace[283487142] 'agreement among raft nodes before linearized reading' (duration: 188.984158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.967561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.639085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:02.967652Z","caller":"traceutil/trace.go:171","msg":"trace[718095895] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1308; }","duration":"138.829972ms","start":"2026-07-22T14:17:02.8288Z","end":"2026-07-22T14:17:02.96763Z","steps":["trace[718095895] 'count revisions from in-memory index tree' (duration: 138.507282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:02.9681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.052618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:02.968206Z","caller":"traceutil/trace.go:171","msg":"trace[670792015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"143.206024ms","start":"2026-07-22T14:17:02.824984Z","end":"2026-07-22T14:17:02.96819Z","steps":["trace[670792015] 'range keys from in-memory index tree' (duration: 142.966806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:04.058897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.455642ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-22T14:17:04.059031Z","caller":"traceutil/trace.go:171","msg":"trace[309624799] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"606.081484ms","start":"2026-07-22T14:17:03.452924Z","end":"2026-07-22T14:17:04.059006Z","steps":["trace[309624799] 'read index received' (duration: 194.460646ms)","trace[309624799] 'applied index is now lower than readState.Index' (duration: 411.618258ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T14:17:04.059255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.312511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-22T14:17:04.059314Z","caller":"traceutil/trace.go:171","msg":"trace[1025583711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"606.425824ms","start":"2026-07-22T14:17:03.452876Z","end":"2026-07-22T14:17:04.059302Z","steps":["trace[1025583711] 'agreement among raft nodes before linearized reading' (duration: 606.205977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:04.059353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:03.452854Z","time spent":"606.487697ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T14:17:14.674703Z","caller":"traceutil/trace.go:171","msg":"trace[1161354226] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"536.867642ms","start":"2026-07-22T14:17:14.137802Z","end":"2026-07-22T14:17:14.67467Z","steps":["trace[1161354226] 'process raft request' (duration: 536.517781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:14.674913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:14.137785Z","time spent":"537.001867ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:17:15.352625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125956228004073932,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T14:17:15.72332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.940098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:15.723484Z","caller":"traceutil/trace.go:171","msg":"trace[1834639304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"897.094314ms","start":"2026-07-22T14:17:14.8263Z","end":"2026-07-22T14:17:15.723394Z","steps":["trace[1834639304] 'range keys from in-memory index tree' (duration: 896.810104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.723597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:14.826268Z","time spent":"897.267889ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T14:17:15.723963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.102264ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T14:17:15.72493Z","caller":"traceutil/trace.go:171","msg":"trace[751765077] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1377; }","duration":"448.072866ms","start":"2026-07-22T14:17:15.276845Z","end":"2026-07-22T14:17:15.724918Z","steps":["trace[751765077] 'range keys from in-memory index tree' (duration: 447.091343ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T14:17:15.739358Z","caller":"traceutil/trace.go:171","msg":"trace[1809243879] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"893.615681ms","start":"2026-07-22T14:17:14.845716Z","end":"2026-07-22T14:17:15.739332Z","steps":["trace[1809243879] 'process raft request' (duration: 893.461726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.739534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:14.845684Z","time spent":"893.756815ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T14:17:15.740203Z","caller":"traceutil/trace.go:171","msg":"trace[2028934620] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"888.489274ms","start":"2026-07-22T14:17:14.851691Z","end":"2026-07-22T14:17:15.74018Z","steps":["trace[2028934620] 'read index received' (duration: 887.569225ms)","trace[2028934620] 'applied index is now lower than readState.Index' (duration: 918.739µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T14:17:15.740457Z","caller":"traceutil/trace.go:171","msg":"trace[377430312] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"508.219444ms","start":"2026-07-22T14:17:15.232221Z","end":"2026-07-22T14:17:15.74044Z","steps":["trace[377430312] 'process raft request' (duration: 507.868982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.740609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:15.232196Z","time spent":"508.360689ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T14:17:15.740849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.322937ms","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-07-22T14:17:15.740907Z","caller":"traceutil/trace.go:171","msg":"trace[1387063443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1379; }","duration":"887.402389ms","start":"2026-07-22T14:17:14.853488Z","end":"2026-07-22T14:17:15.74089Z","steps":["trace[1387063443] 'agreement among raft nodes before linearized reading' (duration: 887.040008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.740952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:14.853477Z","time spent":"887.463231ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-22T14:17:15.741077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.366613ms","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-07-22T14:17:15.741129Z","caller":"traceutil/trace.go:171","msg":"trace[1597922455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1379; }","duration":"889.457756ms","start":"2026-07-22T14:17:14.851658Z","end":"2026-07-22T14:17:15.741116Z","steps":["trace[1597922455] 'agreement among raft nodes before linearized reading' (duration: 889.307781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.741155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:14.851643Z","time spent":"889.506627ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T14:17:15.741225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.569907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-22T14:17:15.741284Z","caller":"traceutil/trace.go:171","msg":"trace[769402023] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1379; }","duration":"233.66639ms","start":"2026-07-22T14:17:15.507603Z","end":"2026-07-22T14:17:15.741269Z","steps":["trace[769402023] 'agreement among raft nodes before linearized reading' (duration: 233.518355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.741357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.731255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T14:17:15.74141Z","caller":"traceutil/trace.go:171","msg":"trace[1616014953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1379; }","duration":"885.798097ms","start":"2026-07-22T14:17:14.855598Z","end":"2026-07-22T14:17:15.741396Z","steps":["trace[1616014953] 'agreement among raft nodes before linearized reading' (duration: 885.644792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T14:17:15.741466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T14:17:14.855592Z","time spent":"885.862889ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}