{"level":"warn","ts":"2026-04-07T09:40:44.197586Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T09:40:44.197666Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-04-07T09:40:44.197727Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T09:40:44.19774Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-07T09:40:44.197756Z","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-04-07T09:40:44.198366Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-04-07T09:40:44.198435Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-04-07T09:40:44.202813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.180403ms"} {"level":"info","ts":"2026-04-07T09:40:44.208669Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-04-07T09:40:44.208744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T09:40:44.208762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-04-07T09:40:44.208776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T09:40:44.208784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-04-07T09:40:44.20882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-04-07T09:40:44.218287Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T09:40:44.219805Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T09:40:44.220891Z","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-04-07T09:40:44.223785Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T09:40:44.224379Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T09:40:44.224513Z","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-04-07T09:40:44.2246Z","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-04-07T09:40:44.224611Z","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-04-07T09:40:44.225154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-04-07T09:40:44.226049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-07T09:40:44.230495Z","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-04-07T09:40:44.230764Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T09:40:44.230813Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T09:40:44.230907Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-07T09:40:44.230965Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-07T09:40:45.009978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T09:40:45.010021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T09:40:45.010043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-04-07T09:40:45.010054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-04-07T09:40:45.01006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-07T09:40:45.010068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-04-07T09:40:45.010076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-07T09:40:45.010959Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:40:45.012005Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T09:40:45.012033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:40:45.012198Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:40:45.01231Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:40:45.012046Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:40:45.012346Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:40:45.012965Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T09:40:45.012989Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T09:40:45.01534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T09:40:45.01541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-04-07T09:40:46.44219Z","caller":"traceutil/trace.go:171","msg":"trace[1540310886] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"133.638874ms","start":"2026-04-07T09:40:46.308532Z","end":"2026-04-07T09:40:46.442171Z","steps":["trace[1540310886] 'process raft request' (duration: 133.484939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.500376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058529667s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:40:47.592597Z","caller":"traceutil/trace.go:171","msg":"trace[242597780] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.205242665s","start":"2026-04-07T09:40:46.387317Z","end":"2026-04-07T09:40:47.59256Z","steps":["trace[242597780] 'process raft request' (duration: 1.20510072s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.592693Z","caller":"traceutil/trace.go:171","msg":"trace[1998725895] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.272069767s","start":"2026-04-07T09:40:46.320608Z","end":"2026-04-07T09:40:47.592677Z","steps":["trace[1998725895] 'read index received' (duration: 121.374626ms)","trace[1998725895] 'applied index is now lower than readState.Index' (duration: 1.150694351s)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:40:47.592809Z","caller":"traceutil/trace.go:171","msg":"trace[311333180] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.283343873s","start":"2026-04-07T09:40:46.30939Z","end":"2026-04-07T09:40:47.592734Z","steps":["trace[311333180] 'process raft request' (duration: 1.19116266s)","trace[311333180] 'compare' (duration: 91.739169ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:40:47.59285Z","caller":"traceutil/trace.go:171","msg":"trace[42476425] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.204268443s","start":"2026-04-07T09:40:46.388553Z","end":"2026-04-07T09:40:47.592821Z","steps":["trace[42476425] 'process raft request' (duration: 1.204003205s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.592892Z","caller":"traceutil/trace.go:171","msg":"trace[2080103365] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.185554283s","start":"2026-04-07T09:40:46.407323Z","end":"2026-04-07T09:40:47.592877Z","steps":["trace[2080103365] 'process raft request' (duration: 1.185312795s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.592927Z","caller":"traceutil/trace.go:171","msg":"trace[1917309677] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.205261435s","start":"2026-04-07T09:40:46.387654Z","end":"2026-04-07T09:40:47.592915Z","steps":["trace[1917309677] 'process raft request' (duration: 1.204800161s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.592942Z","caller":"traceutil/trace.go:171","msg":"trace[1024179178] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.204294575s","start":"2026-04-07T09:40:46.388637Z","end":"2026-04-07T09:40:47.592931Z","steps":["trace[1024179178] 'process raft request' (duration: 1.203943134s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.59298Z","caller":"traceutil/trace.go:171","msg":"trace[640201096] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.204931855s","start":"2026-04-07T09:40:46.388034Z","end":"2026-04-07T09:40:47.592966Z","steps":["trace[640201096] 'process raft request' (duration: 1.204445999s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.593024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272377647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:47.593076Z","caller":"traceutil/trace.go:171","msg":"trace[153423084] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.27248902s","start":"2026-04-07T09:40:46.320578Z","end":"2026-04-07T09:40:47.593067Z","steps":["trace[153423084] 'agreement among raft nodes before linearized reading' (duration: 1.272329885s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.593106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.320565Z","time spent":"1.272532252s","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-07T09:40:47.59322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232342931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:47.593253Z","caller":"traceutil/trace.go:171","msg":"trace[1944809158] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.232407833s","start":"2026-04-07T09:40:46.360834Z","end":"2026-04-07T09:40:47.593242Z","steps":["trace[1944809158] 'agreement among raft nodes before linearized reading' (duration: 1.232355912s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.593278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.360812Z","time spent":"1.232460085s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T09:40:47.593292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.387301Z","time spent":"1.205383329s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:40:47.593128Z","caller":"traceutil/trace.go:171","msg":"trace[1160878193] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.199036858s","start":"2026-04-07T09:40:46.394066Z","end":"2026-04-07T09:40:47.593103Z","steps":["trace[1160878193] 'process raft request' (duration: 1.198536892s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.592684Z","caller":"traceutil/trace.go:171","msg":"trace[1953496358] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.204161271s","start":"2026-04-07T09:40:46.388481Z","end":"2026-04-07T09:40:47.592643Z","steps":["trace[1953496358] 'process raft request' (duration: 1.204043687s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.593442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.644907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T09:40:47.593433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.394058Z","time spent":"1.199314838s","remote":"127.0.0.1:43752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:40:47.593468Z","caller":"traceutil/trace.go:171","msg":"trace[736574323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"172.669527ms","start":"2026-04-07T09:40:47.420791Z","end":"2026-04-07T09:40:47.59346Z","steps":["trace[736574323] 'agreement among raft nodes before linearized reading' (duration: 172.623216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.593585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.930823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:47.593613Z","caller":"traceutil/trace.go:171","msg":"trace[1894483742] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"307.985415ms","start":"2026-04-07T09:40:47.28562Z","end":"2026-04-07T09:40:47.593605Z","steps":["trace[1894483742] 'agreement among raft nodes before linearized reading' (duration: 307.948704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.593625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.388466Z","time spent":"1.204929954s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:47.593637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.285605Z","time spent":"308.025886ms","remote":"127.0.0.1:43834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-07T09:40:47.593663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.309373Z","time spent":"1.283483257s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:40:47.59376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.160301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:47.593786Z","caller":"traceutil/trace.go:171","msg":"trace[683698600] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"308.212212ms","start":"2026-04-07T09:40:47.285567Z","end":"2026-04-07T09:40:47.593779Z","steps":["trace[683698600] 'agreement among raft nodes before linearized reading' (duration: 308.167941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.59381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.285551Z","time spent":"308.253793ms","remote":"127.0.0.1:43826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-07T09:40:47.593849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208808347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T09:40:47.593889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.388546Z","time spent":"1.204349457s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:47.593965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156331979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T09:40:47.593963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206698581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:47.594001Z","caller":"traceutil/trace.go:171","msg":"trace[1481182957] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.15637713s","start":"2026-04-07T09:40:46.437613Z","end":"2026-04-07T09:40:47.59399Z","steps":["trace[1481182957] 'agreement among raft nodes before linearized reading' (duration: 1.156318628s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.594012Z","caller":"traceutil/trace.go:171","msg":"trace[6022633] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.206766032s","start":"2026-04-07T09:40:46.387232Z","end":"2026-04-07T09:40:47.593998Z","steps":["trace[6022633] 'agreement among raft nodes before linearized reading' (duration: 1.20670063s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.594044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.387219Z","time spent":"1.206817145s","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-07T09:40:47.594043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.437607Z","time spent":"1.156426161s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T09:40:47.594149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.407295Z","time spent":"1.185625844s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:47.594247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208364333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:47.593904Z","caller":"traceutil/trace.go:171","msg":"trace[1454973810] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:0; response_revision:11; }","duration":"1.208917491s","start":"2026-04-07T09:40:46.38497Z","end":"2026-04-07T09:40:47.593888Z","steps":["trace[1454973810] 'agreement among raft nodes before linearized reading' (duration: 1.208828488s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:47.594291Z","caller":"traceutil/trace.go:171","msg":"trace[1146658270] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.208439365s","start":"2026-04-07T09:40:46.385838Z","end":"2026-04-07T09:40:47.594278Z","steps":["trace[1146658270] 'agreement among raft nodes before linearized reading' (duration: 1.208371943s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:47.594311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.384953Z","time spent":"1.209346704s","remote":"127.0.0.1:43510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-04-07T09:40:47.594344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.385824Z","time spent":"1.208498758s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T09:40:47.594359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.387634Z","time spent":"1.205321927s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:47.594523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.388623Z","time spent":"1.204345746s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:47.594608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:46.388022Z","time spent":"1.204988886s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:48.100944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807267460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:40:48.77595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.393832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-07T09:40:48.776049Z","caller":"traceutil/trace.go:171","msg":"trace[2035477348] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.175843405s","start":"2026-04-07T09:40:47.600188Z","end":"2026-04-07T09:40:48.776032Z","steps":["trace[2035477348] 'process raft request' (duration: 591.182076ms)","trace[2035477348] 'compare' (duration: 584.274117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:40:48.776102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600165Z","time spent":"1.175918248s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:40:48.776754Z","caller":"traceutil/trace.go:171","msg":"trace[390995493] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.176127214s","start":"2026-04-07T09:40:47.600601Z","end":"2026-04-07T09:40:48.776728Z","steps":["trace[390995493] 'process raft request' (duration: 1.175822895s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.776935Z","caller":"traceutil/trace.go:171","msg":"trace[1801767468] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"1.176255299s","start":"2026-04-07T09:40:47.600647Z","end":"2026-04-07T09:40:48.776902Z","steps":["trace[1801767468] 'read index received' (duration: 590.727392ms)","trace[1801767468] 'applied index is now lower than readState.Index' (duration: 585.526957ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:40:48.77708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176419634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-04-07T09:40:48.777934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600578Z","time spent":"1.177193228s","remote":"127.0.0.1:43834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:40:48.777979Z","caller":"traceutil/trace.go:171","msg":"trace[1849088632] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"1.177341873s","start":"2026-04-07T09:40:47.600618Z","end":"2026-04-07T09:40:48.77796Z","steps":["trace[1849088632] 'agreement among raft nodes before linearized reading' (duration: 1.176365042s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.778039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600606Z","time spent":"1.177412475s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T09:40:48.778125Z","caller":"traceutil/trace.go:171","msg":"trace[521836975] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.172368246s","start":"2026-04-07T09:40:47.605723Z","end":"2026-04-07T09:40:48.778091Z","steps":["trace[521836975] 'process raft request' (duration: 1.171096055s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.778225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.605713Z","time spent":"1.172468809s","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:40:48.778258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177508888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:48.7783Z","caller":"traceutil/trace.go:171","msg":"trace[1743384144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"1.177580811s","start":"2026-04-07T09:40:47.600707Z","end":"2026-04-07T09:40:48.778287Z","steps":["trace[1743384144] 'agreement among raft nodes before linearized reading' (duration: 1.177510218s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.778323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.036409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-07T09:40:48.778347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600696Z","time spent":"1.177641272s","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:40:48.778364Z","caller":"traceutil/trace.go:171","msg":"trace[76386222] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"285.119182ms","start":"2026-04-07T09:40:48.493231Z","end":"2026-04-07T09:40:48.778351Z","steps":["trace[76386222] 'agreement among raft nodes before linearized reading' (duration: 285.041119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.77839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.712485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:48.778423Z","caller":"traceutil/trace.go:171","msg":"trace[1284561214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"356.778547ms","start":"2026-04-07T09:40:48.421636Z","end":"2026-04-07T09:40:48.778414Z","steps":["trace[1284561214] 'agreement among raft nodes before linearized reading' (duration: 356.734555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.778474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:48.421608Z","time spent":"356.838029ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:40:48.778352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.033496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:48.777122Z","caller":"traceutil/trace.go:171","msg":"trace[355005458] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.176364502s","start":"2026-04-07T09:40:47.600745Z","end":"2026-04-07T09:40:48.777109Z","steps":["trace[355005458] 'process raft request' (duration: 1.175756593s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.777293Z","caller":"traceutil/trace.go:171","msg":"trace[778904926] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.176349642s","start":"2026-04-07T09:40:47.600929Z","end":"2026-04-07T09:40:48.777279Z","steps":["trace[778904926] 'process raft request' (duration: 1.175623739s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.777344Z","caller":"traceutil/trace.go:171","msg":"trace[1048304925] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"1.176357732s","start":"2026-04-07T09:40:47.600978Z","end":"2026-04-07T09:40:48.777336Z","steps":["trace[1048304925] 'process raft request' (duration: 1.175618278s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.777384Z","caller":"traceutil/trace.go:171","msg":"trace[1821404017] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.17407981s","start":"2026-04-07T09:40:47.603292Z","end":"2026-04-07T09:40:48.777372Z","steps":["trace[1821404017] 'process raft request' (duration: 1.173341786s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.777432Z","caller":"traceutil/trace.go:171","msg":"trace[1693735995] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.174054999s","start":"2026-04-07T09:40:47.603352Z","end":"2026-04-07T09:40:48.777407Z","steps":["trace[1693735995] 'process raft request' (duration: 1.173341866s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.777465Z","caller":"traceutil/trace.go:171","msg":"trace[479139875] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.174006247s","start":"2026-04-07T09:40:47.60345Z","end":"2026-04-07T09:40:48.777457Z","steps":["trace[479139875] 'process raft request' (duration: 1.173276724s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:40:48.777496Z","caller":"traceutil/trace.go:171","msg":"trace[1183526068] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.173855902s","start":"2026-04-07T09:40:47.603632Z","end":"2026-04-07T09:40:48.777488Z","steps":["trace[1183526068] 'process raft request' (duration: 1.173128099s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.778564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175070191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T09:40:48.779488Z","caller":"traceutil/trace.go:171","msg":"trace[1344118748] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20; }","duration":"1.176050962s","start":"2026-04-07T09:40:47.603423Z","end":"2026-04-07T09:40:48.779474Z","steps":["trace[1344118748] 'agreement among raft nodes before linearized reading' (duration: 1.175013809s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.779527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.603403Z","time spent":"1.176111885s","remote":"127.0.0.1:43830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-07T09:40:48.779615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600961Z","time spent":"1.178622004s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:40:48.779636Z","caller":"traceutil/trace.go:171","msg":"trace[209726652] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"975.337248ms","start":"2026-04-07T09:40:47.80428Z","end":"2026-04-07T09:40:48.779617Z","steps":["trace[209726652] 'agreement among raft nodes before linearized reading' (duration: 974.040177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.779697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.804263Z","time spent":"975.418061ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-07T09:40:48.779832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600717Z","time spent":"1.179081608s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:48.779992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.600921Z","time spent":"1.179042997s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:48.778646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119577697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-07T09:40:48.780415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.603348Z","time spent":"1.177046603s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:40:48.780425Z","caller":"traceutil/trace.go:171","msg":"trace[877723778] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.121385215s","start":"2026-04-07T09:40:47.659022Z","end":"2026-04-07T09:40:48.780407Z","steps":["trace[877723778] 'agreement among raft nodes before linearized reading' (duration: 1.119589438s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:40:48.780452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.603286Z","time spent":"1.177133826s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:48.780493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.659005Z","time spent":"1.121470507s","remote":"127.0.0.1:43602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T09:40:48.780522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.603446Z","time spent":"1.177059004s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:40:48.780368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:40:47.60362Z","time spent":"1.176720614s","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.140768Z","caller":"traceutil/trace.go:171","msg":"trace[1465913294] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"590.187294ms","start":"2026-04-07T09:41:16.550556Z","end":"2026-04-07T09:41:17.140743Z","steps":["trace[1465913294] 'process raft request' (duration: 590.012099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.140909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.550535Z","time spent":"590.310668ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.149858Z","caller":"traceutil/trace.go:171","msg":"trace[1356747121] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"561.084224ms","start":"2026-04-07T09:41:16.58875Z","end":"2026-04-07T09:41:17.149834Z","steps":["trace[1356747121] 'process raft request' (duration: 560.525936ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:17.150088Z","caller":"traceutil/trace.go:171","msg":"trace[484670942] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"558.857644ms","start":"2026-04-07T09:41:16.5912Z","end":"2026-04-07T09:41:17.150057Z","steps":["trace[484670942] 'process raft request' (duration: 558.235654ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:17.150206Z","caller":"traceutil/trace.go:171","msg":"trace[335052582] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"553.320569ms","start":"2026-04-07T09:41:16.596873Z","end":"2026-04-07T09:41:17.150193Z","steps":["trace[335052582] 'process raft request' (duration: 552.673569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.1503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.591184Z","time spent":"559.028659ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:41:17.150312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.596829Z","time spent":"553.444883ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.150118Z","caller":"traceutil/trace.go:171","msg":"trace[577495342] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"557.47717ms","start":"2026-04-07T09:41:16.592624Z","end":"2026-04-07T09:41:17.150101Z","steps":["trace[577495342] 'process raft request' (duration: 556.851451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.150497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.592618Z","time spent":"557.856932ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.150585Z","caller":"traceutil/trace.go:171","msg":"trace[479886619] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"548.138655ms","start":"2026-04-07T09:41:16.602434Z","end":"2026-04-07T09:41:17.150573Z","steps":["trace[479886619] 'process raft request' (duration: 547.616149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.15064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.602425Z","time spent":"548.198617ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:41:17.150033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.588733Z","time spent":"561.198448ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.150767Z","caller":"traceutil/trace.go:171","msg":"trace[1012758533] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"549.633402ms","start":"2026-04-07T09:41:16.601126Z","end":"2026-04-07T09:41:17.15076Z","steps":["trace[1012758533] 'process raft request' (duration: 548.704613ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:17.150805Z","caller":"traceutil/trace.go:171","msg":"trace[1973764364] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"548.068203ms","start":"2026-04-07T09:41:16.60273Z","end":"2026-04-07T09:41:17.150798Z","steps":["trace[1973764364] 'process raft request' (duration: 547.410503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.150824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.601109Z","time spent":"549.697725ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:41:17.150883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.602723Z","time spent":"548.111275ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.150993Z","caller":"traceutil/trace.go:171","msg":"trace[1220862914] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"435.017089ms","start":"2026-04-07T09:41:16.71597Z","end":"2026-04-07T09:41:17.150987Z","steps":["trace[1220862914] 'process raft request' (duration: 434.675339ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:17.150994Z","caller":"traceutil/trace.go:171","msg":"trace[1751647479] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"541.786515ms","start":"2026-04-07T09:41:16.609119Z","end":"2026-04-07T09:41:17.150906Z","steps":["trace[1751647479] 'process raft request' (duration: 541.175926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.151028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.715952Z","time spent":"435.061681ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.151079Z","caller":"traceutil/trace.go:171","msg":"trace[237023579] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"515.458502ms","start":"2026-04-07T09:41:16.635615Z","end":"2026-04-07T09:41:17.151073Z","steps":["trace[237023579] 'process raft request' (duration: 514.870644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.151109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.6356Z","time spent":"515.497134ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:41:17.151114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.609099Z","time spent":"541.95806ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T09:41:17.150756Z","caller":"traceutil/trace.go:171","msg":"trace[599667278] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"547.929928ms","start":"2026-04-07T09:41:16.602804Z","end":"2026-04-07T09:41:17.150734Z","steps":["trace[599667278] 'process raft request' (duration: 547.407172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:17.151194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:16.602799Z","time spent":"548.382173ms","remote":"127.0.0.1:43526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:41:21.721341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807268583,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:41:21.859722Z","caller":"traceutil/trace.go:171","msg":"trace[227124934] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"638.897173ms","start":"2026-04-07T09:41:21.220807Z","end":"2026-04-07T09:41:21.859704Z","steps":["trace[227124934] 'read index received' (duration: 638.350985ms)","trace[227124934] 'applied index is now lower than readState.Index' (duration: 545.088µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:41:21.860315Z","caller":"traceutil/trace.go:171","msg":"trace[894146847] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"748.373313ms","start":"2026-04-07T09:41:21.111934Z","end":"2026-04-07T09:41:21.860307Z","steps":["trace[894146847] 'process raft request' (duration: 747.396672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:21.86039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:21.111906Z","time spent":"748.430855ms","remote":"127.0.0.1:43576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:41:21.860536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.731119ms","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-04-07T09:41:21.860556Z","caller":"traceutil/trace.go:171","msg":"trace[2023807352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"639.751519ms","start":"2026-04-07T09:41:21.2208Z","end":"2026-04-07T09:41:21.860551Z","steps":["trace[2023807352] 'agreement among raft nodes before linearized reading' (duration: 639.685437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:21.86057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:21.220742Z","time spent":"639.825972ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:41:21.860676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.77164ms","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-04-07T09:41:21.860693Z","caller":"traceutil/trace.go:171","msg":"trace[798382079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"639.835913ms","start":"2026-04-07T09:41:21.220853Z","end":"2026-04-07T09:41:21.860689Z","steps":["trace[798382079] 'agreement among raft nodes before linearized reading' (duration: 639.796332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:21.860705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:21.220829Z","time spent":"639.874004ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:41:21.860807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.946961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:21.860825Z","caller":"traceutil/trace.go:171","msg":"trace[738585297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"493.006203ms","start":"2026-04-07T09:41:21.367815Z","end":"2026-04-07T09:41:21.860821Z","steps":["trace[738585297] 'agreement among raft nodes before linearized reading' (duration: 492.978092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:21.860837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:21.367794Z","time spent":"493.040363ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:41:22.175063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.658529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:41:22.175315Z","caller":"traceutil/trace.go:171","msg":"trace[1555758446] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"304.970779ms","start":"2026-04-07T09:41:21.870314Z","end":"2026-04-07T09:41:22.175284Z","steps":["trace[1555758446] 'process raft request' (duration: 198.99713ms)","trace[1555758446] 'compare' (duration: 105.503265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:41:22.175467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:21.870304Z","time spent":"305.091213ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:22.17552Z","caller":"traceutil/trace.go:171","msg":"trace[317467339] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"254.465193ms","start":"2026-04-07T09:41:21.921042Z","end":"2026-04-07T09:41:22.175507Z","steps":["trace[317467339] 'process raft request' (duration: 254.429922ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:22.175613Z","caller":"traceutil/trace.go:171","msg":"trace[28304423] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"300.25958ms","start":"2026-04-07T09:41:21.875327Z","end":"2026-04-07T09:41:22.175586Z","steps":["trace[28304423] 'process raft request' (duration: 300.079044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:22.175732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:21.875306Z","time spent":"300.364004ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:23.657422Z","caller":"traceutil/trace.go:171","msg":"trace[1639585273] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"148.861505ms","start":"2026-04-07T09:41:23.50853Z","end":"2026-04-07T09:41:23.657392Z","steps":["trace[1639585273] 'process raft request' (duration: 148.242495ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:24.085059Z","caller":"traceutil/trace.go:171","msg":"trace[1800317320] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"403.561416ms","start":"2026-04-07T09:41:23.681472Z","end":"2026-04-07T09:41:24.085034Z","steps":["trace[1800317320] 'process raft request' (duration: 403.401761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:24.085293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:23.681449Z","time spent":"403.68511ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:41:25.77548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:25.271294Z","time spent":"504.178526ms","remote":"127.0.0.1:60730","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T09:41:25.775804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369794804s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:41:25.775895Z","caller":"traceutil/trace.go:171","msg":"trace[1537985211] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"1.596084977s","start":"2026-04-07T09:41:24.179794Z","end":"2026-04-07T09:41:25.775879Z","steps":["trace[1537985211] 'read index received' (duration: 226.103137ms)","trace[1537985211] 'applied index is now lower than readState.Index' (duration: 1.36998113s)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:41:25.775931Z","caller":"traceutil/trace.go:171","msg":"trace[453356817] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.054502506s","start":"2026-04-07T09:41:23.721396Z","end":"2026-04-07T09:41:25.775899Z","steps":["trace[453356817] 'process raft request' (duration: 684.578508ms)","trace[453356817] 'compare' (duration: 1.369631849s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:41:25.776086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596293334s","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-04-07T09:41:25.776095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596289334s","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-04-07T09:41:25.776114Z","caller":"traceutil/trace.go:171","msg":"trace[1544818267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.596361306s","start":"2026-04-07T09:41:24.179745Z","end":"2026-04-07T09:41:25.776107Z","steps":["trace[1544818267] 'agreement among raft nodes before linearized reading' (duration: 1.596208111s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:25.776143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:24.179726Z","time spent":"1.596410868s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T09:41:25.776141Z","caller":"traceutil/trace.go:171","msg":"trace[850741241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.596378806s","start":"2026-04-07T09:41:24.179745Z","end":"2026-04-07T09:41:25.776124Z","steps":["trace[850741241] 'agreement among raft nodes before linearized reading' (duration: 1.59616981s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:25.776139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:23.721372Z","time spent":"2.05462016s","remote":"127.0.0.1:43830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:41:25.77618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:24.179725Z","time spent":"1.596447609s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T09:41:25.776237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490356045s","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-04-07T09:41:25.776305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418169854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:25.776312Z","caller":"traceutil/trace.go:171","msg":"trace[1493304480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"1.490460078s","start":"2026-04-07T09:41:24.28583Z","end":"2026-04-07T09:41:25.77629Z","steps":["trace[1493304480] 'agreement among raft nodes before linearized reading' (duration: 1.490240922s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:25.776336Z","caller":"traceutil/trace.go:171","msg":"trace[589557533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.418216185s","start":"2026-04-07T09:41:24.358108Z","end":"2026-04-07T09:41:25.776324Z","steps":["trace[589557533] 'agreement among raft nodes before linearized reading' (duration: 1.418175183s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:25.776356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:24.28582Z","time spent":"1.4905277s","remote":"127.0.0.1:43762","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-04-07T09:41:25.776374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:24.358097Z","time spent":"1.418271127s","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:41:25.776514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488021681s","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-04-07T09:41:25.776644Z","caller":"traceutil/trace.go:171","msg":"trace[1170512215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"1.488171046s","start":"2026-04-07T09:41:24.288457Z","end":"2026-04-07T09:41:25.776628Z","steps":["trace[1170512215] 'agreement among raft nodes before linearized reading' (duration: 1.487959359s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:25.77668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:24.288448Z","time spent":"1.488223718s","remote":"127.0.0.1:43762","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-04-07T09:41:26.946128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.315354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:41:26.946149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.267492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:41:26.946182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.104125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:26.946212Z","caller":"traceutil/trace.go:171","msg":"trace[78128911] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:626; }","duration":"195.457988ms","start":"2026-04-07T09:41:26.750736Z","end":"2026-04-07T09:41:26.946194Z","steps":["trace[78128911] 'range keys from in-memory index tree' (duration: 195.163459ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:26.946223Z","caller":"traceutil/trace.go:171","msg":"trace[1857114741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:626; }","duration":"195.375306ms","start":"2026-04-07T09:41:26.75083Z","end":"2026-04-07T09:41:26.946205Z","steps":["trace[1857114741] 'count revisions from in-memory index tree' (duration: 195.21574ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:26.946238Z","caller":"traceutil/trace.go:171","msg":"trace[509939444] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:626; }","duration":"194.180468ms","start":"2026-04-07T09:41:26.75204Z","end":"2026-04-07T09:41:26.94622Z","steps":["trace[509939444] 'range keys from in-memory index tree' (duration: 194.017233ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:26.948247Z","caller":"traceutil/trace.go:171","msg":"trace[199471829] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"178.73699ms","start":"2026-04-07T09:41:26.769496Z","end":"2026-04-07T09:41:26.948233Z","steps":["trace[199471829] 'read index received' (duration: 177.947475ms)","trace[199471829] 'applied index is now lower than readState.Index' (duration: 788.865µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:41:26.948358Z","caller":"traceutil/trace.go:171","msg":"trace[770221418] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"180.65535ms","start":"2026-04-07T09:41:26.767675Z","end":"2026-04-07T09:41:26.94833Z","steps":["trace[770221418] 'process raft request' (duration: 180.086412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:26.948376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.865744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:26.948424Z","caller":"traceutil/trace.go:171","msg":"trace[1271861780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"178.929785ms","start":"2026-04-07T09:41:26.769481Z","end":"2026-04-07T09:41:26.94841Z","steps":["trace[1271861780] 'agreement among raft nodes before linearized reading' (duration: 178.854133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:26.948411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.804652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:26.948485Z","caller":"traceutil/trace.go:171","msg":"trace[644574328] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"178.894505ms","start":"2026-04-07T09:41:26.769566Z","end":"2026-04-07T09:41:26.94846Z","steps":["trace[644574328] 'agreement among raft nodes before linearized reading' (duration: 178.803372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:26.948701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.508332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:26.948754Z","caller":"traceutil/trace.go:171","msg":"trace[1426867198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:627; }","duration":"178.590065ms","start":"2026-04-07T09:41:26.770153Z","end":"2026-04-07T09:41:26.948743Z","steps":["trace[1426867198] 'agreement among raft nodes before linearized reading' (duration: 178.511762ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:27.865011Z","caller":"traceutil/trace.go:171","msg":"trace[440445777] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"154.718981ms","start":"2026-04-07T09:41:27.710205Z","end":"2026-04-07T09:41:27.864924Z","steps":["trace[440445777] 'process raft request' (duration: 154.514864ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:30.846631Z","caller":"traceutil/trace.go:171","msg":"trace[1369100197] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"103.921358ms","start":"2026-04-07T09:41:30.742681Z","end":"2026-04-07T09:41:30.846603Z","steps":["trace[1369100197] 'process raft request' (duration: 103.622328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:31.751058Z","caller":"traceutil/trace.go:171","msg":"trace[1454383746] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"100.443609ms","start":"2026-04-07T09:41:31.650592Z","end":"2026-04-07T09:41:31.751036Z","steps":["trace[1454383746] 'process raft request' (duration: 95.139283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:42.972891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.570301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:41:42.972992Z","caller":"traceutil/trace.go:171","msg":"trace[672343900] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"231.266195ms","start":"2026-04-07T09:41:42.741709Z","end":"2026-04-07T09:41:42.972976Z","steps":["trace[672343900] 'process raft request' (duration: 123.533079ms)","trace[672343900] 'compare' (duration: 107.412556ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:41:43.058142Z","caller":"traceutil/trace.go:171","msg":"trace[2106177389] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"316.18553ms","start":"2026-04-07T09:41:42.741925Z","end":"2026-04-07T09:41:43.058111Z","steps":["trace[2106177389] 'process raft request' (duration: 315.957313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:43.058314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:42.741915Z","time spent":"316.327634ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:43.058845Z","caller":"traceutil/trace.go:171","msg":"trace[1045814731] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"312.829004ms","start":"2026-04-07T09:41:42.745974Z","end":"2026-04-07T09:41:43.058803Z","steps":["trace[1045814731] 'process raft request' (duration: 312.05871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:43.05914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:42.745937Z","time spent":"313.049591ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:43.369478Z","caller":"traceutil/trace.go:171","msg":"trace[1888234268] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"302.471172ms","start":"2026-04-07T09:41:43.066981Z","end":"2026-04-07T09:41:43.369452Z","steps":["trace[1888234268] 'process raft request' (duration: 302.275286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:43.369644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:43.066962Z","time spent":"302.586875ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:43.562113Z","caller":"traceutil/trace.go:171","msg":"trace[106687333] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"495.074281ms","start":"2026-04-07T09:41:43.067015Z","end":"2026-04-07T09:41:43.562089Z","steps":["trace[106687333] 'process raft request' (duration: 494.851584ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:43.562145Z","caller":"traceutil/trace.go:171","msg":"trace[1438336671] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"495.098031ms","start":"2026-04-07T09:41:43.067024Z","end":"2026-04-07T09:41:43.562122Z","steps":["trace[1438336671] 'process raft request' (duration: 494.986827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:43.562239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:43.066998Z","time spent":"495.177283ms","remote":"127.0.0.1:43644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:41:43.562264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:43.066999Z","time spent":"495.211764ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:43.562291Z","caller":"traceutil/trace.go:171","msg":"trace[1985325947] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:803; }","duration":"200.913588ms","start":"2026-04-07T09:41:43.361342Z","end":"2026-04-07T09:41:43.562255Z","steps":["trace[1985325947] 'read index received' (duration: 7.915537ms)","trace[1985325947] 'applied index is now lower than readState.Index' (duration: 192.995461ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:41:43.562353Z","caller":"traceutil/trace.go:171","msg":"trace[1694334769] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"344.097223ms","start":"2026-04-07T09:41:43.218249Z","end":"2026-04-07T09:41:43.562346Z","steps":["trace[1694334769] 'process raft request' (duration: 343.799093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:43.562392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:43.218226Z","time spent":"344.144634ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:41:43.562796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.162306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:43.562959Z","caller":"traceutil/trace.go:171","msg":"trace[1319919270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"201.656421ms","start":"2026-04-07T09:41:43.361287Z","end":"2026-04-07T09:41:43.562943Z","steps":["trace[1319919270] 'agreement among raft nodes before linearized reading' (duration: 201.117774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:43.563705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.898058ms","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-04-07T09:41:43.563789Z","caller":"traceutil/trace.go:171","msg":"trace[635898547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"145.036812ms","start":"2026-04-07T09:41:43.418735Z","end":"2026-04-07T09:41:43.563772Z","steps":["trace[635898547] 'agreement among raft nodes before linearized reading' (duration: 143.717851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:46.471774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807269024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:41:46.972697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807269024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:41:47.024724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.061660836s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:41:47.025066Z","caller":"traceutil/trace.go:171","msg":"trace[952528493] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.062058309s","start":"2026-04-07T09:41:45.96297Z","end":"2026-04-07T09:41:47.025029Z","steps":["trace[952528493] 'process raft request' (duration: 1.061898554s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:47.025209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:45.962951Z","time spent":"1.062183583s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:41:47.172277Z","caller":"traceutil/trace.go:171","msg":"trace[447106017] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:823; }","duration":"1.201491876s","start":"2026-04-07T09:41:45.970762Z","end":"2026-04-07T09:41:47.172254Z","steps":["trace[447106017] 'read index received' (duration: 1.054202403s)","trace[447106017] 'applied index is now lower than readState.Index' (duration: 147.288463ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:41:47.172369Z","caller":"traceutil/trace.go:171","msg":"trace[420764041] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"433.047193ms","start":"2026-04-07T09:41:46.739292Z","end":"2026-04-07T09:41:47.172339Z","steps":["trace[420764041] 'process raft request' (duration: 432.849967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:47.172501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.039281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:41:47.172548Z","caller":"traceutil/trace.go:171","msg":"trace[1301986171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"256.127523ms","start":"2026-04-07T09:41:46.916408Z","end":"2026-04-07T09:41:47.172535Z","steps":["trace[1301986171] 'agreement among raft nodes before linearized reading' (duration: 256.03385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:47.172543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:46.739252Z","time spent":"433.188647ms","remote":"127.0.0.1:43732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:41:47.172593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199251116s","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-04-07T09:41:47.172673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.802357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:41:47.172637Z","caller":"traceutil/trace.go:171","msg":"trace[631898100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"1.199344268s","start":"2026-04-07T09:41:45.973279Z","end":"2026-04-07T09:41:47.172623Z","steps":["trace[631898100] 'agreement among raft nodes before linearized reading' (duration: 1.199153462s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:41:47.172709Z","caller":"traceutil/trace.go:171","msg":"trace[1740784221] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:797; }","duration":"706.875879ms","start":"2026-04-07T09:41:46.465823Z","end":"2026-04-07T09:41:47.172699Z","steps":["trace[1740784221] 'agreement among raft nodes before linearized reading' (duration: 706.816638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:47.172745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:46.465802Z","time spent":"706.933282ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:41:47.172751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:45.973271Z","time spent":"1.199461172s","remote":"127.0.0.1:43762","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-04-07T09:41:47.172843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202074743s","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-04-07T09:41:47.172898Z","caller":"traceutil/trace.go:171","msg":"trace[502894534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.202157176s","start":"2026-04-07T09:41:45.970724Z","end":"2026-04-07T09:41:47.172882Z","steps":["trace[502894534] 'agreement among raft nodes before linearized reading' (duration: 1.201678621s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:47.172945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:45.970713Z","time spent":"1.202216358s","remote":"127.0.0.1:43762","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-04-07T09:41:47.172903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199443001s","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-04-07T09:41:47.173132Z","caller":"traceutil/trace.go:171","msg":"trace[14570717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.199693309s","start":"2026-04-07T09:41:45.973411Z","end":"2026-04-07T09:41:47.173104Z","steps":["trace[14570717] 'agreement among raft nodes before linearized reading' (duration: 1.199283266s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:41:47.173189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:41:45.973398Z","time spent":"1.199776931s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T09:42:01.527532Z","caller":"traceutil/trace.go:171","msg":"trace[1130319116] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"270.382869ms","start":"2026-04-07T09:42:01.257129Z","end":"2026-04-07T09:42:01.527511Z","steps":["trace[1130319116] 'read index received' (duration: 270.257555ms)","trace[1130319116] 'applied index is now lower than readState.Index' (duration: 124.454µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:01.527601Z","caller":"traceutil/trace.go:171","msg":"trace[2009498544] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"323.716795ms","start":"2026-04-07T09:42:01.20385Z","end":"2026-04-07T09:42:01.527566Z","steps":["trace[2009498544] 'process raft request' (duration: 323.53139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:01.527791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.695499ms","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-04-07T09:42:01.527826Z","caller":"traceutil/trace.go:171","msg":"trace[288489255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"270.774201ms","start":"2026-04-07T09:42:01.257041Z","end":"2026-04-07T09:42:01.527815Z","steps":["trace[288489255] 'agreement among raft nodes before linearized reading' (duration: 270.579415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:01.527994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.203825Z","time spent":"323.903461ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:01.78658Z","caller":"traceutil/trace.go:171","msg":"trace[141225438] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"467.936572ms","start":"2026-04-07T09:42:01.318599Z","end":"2026-04-07T09:42:01.786535Z","steps":["trace[141225438] 'process raft request' (duration: 446.979157ms)","trace[141225438] 'compare' (duration: 20.545412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:01.786617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.058705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:01.786665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.402808ms","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-04-07T09:42:01.786692Z","caller":"traceutil/trace.go:171","msg":"trace[787406096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"427.189159ms","start":"2026-04-07T09:42:01.359484Z","end":"2026-04-07T09:42:01.786673Z","steps":["trace[787406096] 'agreement among raft nodes before linearized reading' (duration: 427.002973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:01.786668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.743126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-82h58\" ","response":"range_response_count:1 size:5674"} {"level":"warn","ts":"2026-04-07T09:42:01.786712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.318575Z","time spent":"468.081257ms","remote":"127.0.0.1:43556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T09:42:01.786739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.359469Z","time spent":"427.261781ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:42:01.786736Z","caller":"traceutil/trace.go:171","msg":"trace[549202083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"525.507792ms","start":"2026-04-07T09:42:01.261214Z","end":"2026-04-07T09:42:01.786722Z","steps":["trace[549202083] 'agreement among raft nodes before linearized reading' (duration: 525.285435ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:01.786757Z","caller":"traceutil/trace.go:171","msg":"trace[1473320798] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-82h58; range_end:; response_count:1; response_revision:929; }","duration":"331.87099ms","start":"2026-04-07T09:42:01.454867Z","end":"2026-04-07T09:42:01.786738Z","steps":["trace[1473320798] 'agreement among raft nodes before linearized reading' (duration: 331.616422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:01.786787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.261207Z","time spent":"525.571433ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T09:42:01.786801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.454853Z","time spent":"331.940123ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5696,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-82h58\" "} {"level":"info","ts":"2026-04-07T09:42:01.787221Z","caller":"traceutil/trace.go:171","msg":"trace[2017928032] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"258.857819ms","start":"2026-04-07T09:42:01.527598Z","end":"2026-04-07T09:42:01.786456Z","steps":["trace[2017928032] 'read index received' (duration: 238.07657ms)","trace[2017928032] 'applied index is now lower than readState.Index' (duration: 20.779359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:01.787331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.199053ms","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-04-07T09:42:01.7874Z","caller":"traceutil/trace.go:171","msg":"trace[1683134579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"526.306006ms","start":"2026-04-07T09:42:01.261076Z","end":"2026-04-07T09:42:01.787382Z","steps":["trace[1683134579] 'agreement among raft nodes before linearized reading' (duration: 526.084039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:01.78744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.261064Z","time spent":"526.369228ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T09:42:02.149466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.214654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:02.149576Z","caller":"traceutil/trace.go:171","msg":"trace[458155164] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"362.299242ms","start":"2026-04-07T09:42:01.787259Z","end":"2026-04-07T09:42:02.149558Z","steps":["trace[458155164] 'read index received' (duration: 196.105709ms)","trace[458155164] 'applied index is now lower than readState.Index' (duration: 166.191813ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:02.149766Z","caller":"traceutil/trace.go:171","msg":"trace[1920597986] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"617.368782ms","start":"2026-04-07T09:42:01.532349Z","end":"2026-04-07T09:42:02.149717Z","steps":["trace[1920597986] 'process raft request' (duration: 450.849238ms)","trace[1920597986] 'compare' (duration: 166.015388ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:02.149815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.316889ms","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-04-07T09:42:02.149887Z","caller":"traceutil/trace.go:171","msg":"trace[1441402403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"496.435473ms","start":"2026-04-07T09:42:01.653436Z","end":"2026-04-07T09:42:02.149872Z","steps":["trace[1441402403] 'agreement among raft nodes before linearized reading' (duration: 496.245177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.149874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.52316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-2cxdk\" ","response":"range_response_count:1 size:2273"} {"level":"warn","ts":"2026-04-07T09:42:02.149919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.532325Z","time spent":"617.512217ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:02.149932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.653417Z","time spent":"496.505745ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T09:42:02.149965Z","caller":"traceutil/trace.go:171","msg":"trace[1267563968] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-2cxdk; range_end:; response_count:1; response_revision:930; }","duration":"500.631774ms","start":"2026-04-07T09:42:01.649298Z","end":"2026-04-07T09:42:02.14993Z","steps":["trace[1267563968] 'agreement among raft nodes before linearized reading' (duration: 500.450408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.150027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.649281Z","time spent":"500.732567ms","remote":"127.0.0.1:60938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":2295,"request content":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-2cxdk\" "} {"level":"info","ts":"2026-04-07T09:42:02.244905Z","caller":"traceutil/trace.go:171","msg":"trace[822796123] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"453.945195ms","start":"2026-04-07T09:42:01.790935Z","end":"2026-04-07T09:42:02.24488Z","steps":["trace[822796123] 'process raft request' (duration: 453.831491ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:02.244929Z","caller":"traceutil/trace.go:171","msg":"trace[1939544638] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"454.057759ms","start":"2026-04-07T09:42:01.790831Z","end":"2026-04-07T09:42:02.244889Z","steps":["trace[1939544638] 'process raft request' (duration: 453.801891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.245066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.790905Z","time spent":"454.078419ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:02.245123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.790789Z","time spent":"454.228394ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:02.245566Z","caller":"traceutil/trace.go:171","msg":"trace[1743438157] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"449.441055ms","start":"2026-04-07T09:42:01.796114Z","end":"2026-04-07T09:42:02.245555Z","steps":["trace[1743438157] 'process raft request' (duration: 448.700902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.245629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.796103Z","time spent":"449.494376ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:02.429092Z","caller":"traceutil/trace.go:171","msg":"trace[156582006] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"330.275441ms","start":"2026-04-07T09:42:02.098792Z","end":"2026-04-07T09:42:02.429068Z","steps":["trace[156582006] 'process raft request' (duration: 330.058935ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:02.429382Z","caller":"traceutil/trace.go:171","msg":"trace[2089967486] transaction","detail":"{read_only:false; number_of_response:1; response_revision:935; }","duration":"276.354445ms","start":"2026-04-07T09:42:02.153003Z","end":"2026-04-07T09:42:02.429358Z","steps":["trace[2089967486] 'process raft request' (duration: 276.007374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.429387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:02.098765Z","time spent":"330.399644ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:02.429867Z","caller":"traceutil/trace.go:171","msg":"trace[514727450] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:963; }","duration":"280.246498ms","start":"2026-04-07T09:42:02.149606Z","end":"2026-04-07T09:42:02.429853Z","steps":["trace[514727450] 'read index received' (duration: 95.060001ms)","trace[514727450] 'applied index is now lower than readState.Index' (duration: 185.185367ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:02.429933Z","caller":"traceutil/trace.go:171","msg":"trace[1785740134] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"276.501201ms","start":"2026-04-07T09:42:02.153415Z","end":"2026-04-07T09:42:02.429917Z","steps":["trace[1785740134] 'process raft request' (duration: 275.902832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.430019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.047533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:02.430195Z","caller":"traceutil/trace.go:171","msg":"trace[272518351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"642.25918ms","start":"2026-04-07T09:42:01.787912Z","end":"2026-04-07T09:42:02.430172Z","steps":["trace[272518351] 'agreement among raft nodes before linearized reading' (duration: 642.011082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.430228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:01.787891Z","time spent":"642.327892ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:42:02.526036Z","caller":"traceutil/trace.go:171","msg":"trace[1323157923] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"275.703976ms","start":"2026-04-07T09:42:02.250303Z","end":"2026-04-07T09:42:02.526007Z","steps":["trace[1323157923] 'process raft request' (duration: 275.460518ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:02.526315Z","caller":"traceutil/trace.go:171","msg":"trace[892231028] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"275.784978ms","start":"2026-04-07T09:42:02.250508Z","end":"2026-04-07T09:42:02.526293Z","steps":["trace[892231028] 'process raft request' (duration: 275.433217ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:02.526438Z","caller":"traceutil/trace.go:171","msg":"trace[159286983] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"272.340121ms","start":"2026-04-07T09:42:02.254076Z","end":"2026-04-07T09:42:02.526417Z","steps":["trace[159286983] 'process raft request' (duration: 272.168085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:02.526569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.446489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2cxdk\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-04-07T09:42:02.526639Z","caller":"traceutil/trace.go:171","msg":"trace[525928981] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2cxdk; range_end:; response_count:1; response_revision:939; }","duration":"276.563112ms","start":"2026-04-07T09:42:02.25006Z","end":"2026-04-07T09:42:02.526623Z","steps":["trace[525928981] 'agreement among raft nodes before linearized reading' (duration: 276.371036ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:12.395615Z","caller":"traceutil/trace.go:171","msg":"trace[1668956517] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"194.976012ms","start":"2026-04-07T09:42:12.20062Z","end":"2026-04-07T09:42:12.395596Z","steps":["trace[1668956517] 'process raft request' (duration: 194.835858ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:12.516658Z","caller":"traceutil/trace.go:171","msg":"trace[256471091] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"116.78295ms","start":"2026-04-07T09:42:12.399852Z","end":"2026-04-07T09:42:12.516635Z","steps":["trace[256471091] 'process raft request' (duration: 116.747779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:12.516692Z","caller":"traceutil/trace.go:171","msg":"trace[1695989960] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"117.156241ms","start":"2026-04-07T09:42:12.399514Z","end":"2026-04-07T09:42:12.51667Z","steps":["trace[1695989960] 'process raft request' (duration: 37.669157ms)","trace[1695989960] 'compare' (duration: 79.312698ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:12.670153Z","caller":"traceutil/trace.go:171","msg":"trace[1239136223] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"146.768486ms","start":"2026-04-07T09:42:12.52334Z","end":"2026-04-07T09:42:12.670109Z","steps":["trace[1239136223] 'process raft request' (duration: 94.966367ms)","trace[1239136223] 'compare' (duration: 51.660334ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:18.829194Z","caller":"traceutil/trace.go:171","msg":"trace[2026059308] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"105.680693ms","start":"2026-04-07T09:42:18.723484Z","end":"2026-04-07T09:42:18.829165Z","steps":["trace[2026059308] 'process raft request' (duration: 105.469436ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:21.955509Z","caller":"traceutil/trace.go:171","msg":"trace[998112788] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"245.918605ms","start":"2026-04-07T09:42:21.709564Z","end":"2026-04-07T09:42:21.955483Z","steps":["trace[998112788] 'process raft request' (duration: 245.76623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:23.098405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807269475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:42:23.598823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807269475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:42:23.865921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.292335474s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:42:23.866461Z","caller":"traceutil/trace.go:171","msg":"trace[1847372440] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.292945973s","start":"2026-04-07T09:42:22.573489Z","end":"2026-04-07T09:42:23.866435Z","steps":["trace[1847372440] 'process raft request' (duration: 1.292714876s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:23.867067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:22.573469Z","time spent":"1.293478629s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:42:24.29087Z","caller":"traceutil/trace.go:171","msg":"trace[773808221] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1048; }","duration":"1.693369205s","start":"2026-04-07T09:42:22.597471Z","end":"2026-04-07T09:42:24.29084Z","steps":["trace[773808221] 'read index received' (duration: 1.268688825s)","trace[773808221] 'applied index is now lower than readState.Index' (duration: 424.67966ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:24.290953Z","caller":"traceutil/trace.go:171","msg":"trace[2063514570] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.717321784s","start":"2026-04-07T09:42:22.573563Z","end":"2026-04-07T09:42:24.290884Z","steps":["trace[2063514570] 'process raft request' (duration: 1.71686986s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.693599983s","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-04-07T09:42:24.29111Z","caller":"traceutil/trace.go:171","msg":"trace[550264949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"1.693661115s","start":"2026-04-07T09:42:22.597443Z","end":"2026-04-07T09:42:24.291104Z","steps":["trace[550264949] 'agreement among raft nodes before linearized reading' (duration: 1.693537951s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:22.573544Z","time spent":"1.717468829s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:24.291133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:22.59743Z","time spent":"1.693698186s","remote":"127.0.0.1:43762","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-04-07T09:42:24.291151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68679073s","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-04-07T09:42:24.291194Z","caller":"traceutil/trace.go:171","msg":"trace[854085706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.686848222s","start":"2026-04-07T09:42:22.604333Z","end":"2026-04-07T09:42:24.291181Z","steps":["trace[854085706] 'agreement among raft nodes before linearized reading' (duration: 1.686733779s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:22.604327Z","time spent":"1.686892133s","remote":"127.0.0.1:43762","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-04-07T09:42:24.291245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.368805ms","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-04-07T09:42:24.291263Z","caller":"traceutil/trace.go:171","msg":"trace[323812361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"331.415066ms","start":"2026-04-07T09:42:23.959843Z","end":"2026-04-07T09:42:24.291258Z","steps":["trace[323812361] 'agreement among raft nodes before linearized reading' (duration: 331.364085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:23.959823Z","time spent":"331.458618ms","remote":"127.0.0.1:43762","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-04-07T09:42:24.291327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030533783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:42:24.291354Z","caller":"traceutil/trace.go:171","msg":"trace[108862140] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1011; }","duration":"1.030566484s","start":"2026-04-07T09:42:23.260778Z","end":"2026-04-07T09:42:24.291345Z","steps":["trace[108862140] 'agreement among raft nodes before linearized reading' (duration: 1.030516623s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:23.260715Z","time spent":"1.030658427s","remote":"127.0.0.1:43752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:24.29136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.811318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:24.291471Z","caller":"traceutil/trace.go:171","msg":"trace[1361592837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"931.999083ms","start":"2026-04-07T09:42:23.359447Z","end":"2026-04-07T09:42:24.291447Z","steps":["trace[1361592837] 'agreement among raft nodes before linearized reading' (duration: 931.662483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:23.359429Z","time spent":"932.078966ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:42:24.291564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.708252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:42:24.291591Z","caller":"traceutil/trace.go:171","msg":"trace[1674778102] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1011; }","duration":"424.749853ms","start":"2026-04-07T09:42:23.866832Z","end":"2026-04-07T09:42:24.291582Z","steps":["trace[1674778102] 'agreement among raft nodes before linearized reading' (duration: 424.702241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:23.866779Z","time spent":"424.831646ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:24.291611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135216974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:42:24.291645Z","caller":"traceutil/trace.go:171","msg":"trace[1836097085] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1011; }","duration":"1.135279316s","start":"2026-04-07T09:42:23.156356Z","end":"2026-04-07T09:42:24.291635Z","steps":["trace[1836097085] 'agreement among raft nodes before linearized reading' (duration: 1.135164073s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:23.156337Z","time spent":"1.135329597s","remote":"127.0.0.1:43830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:24.291804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449809385s","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-04-07T09:42:24.291878Z","caller":"traceutil/trace.go:171","msg":"trace[1477206285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.449932558s","start":"2026-04-07T09:42:22.841928Z","end":"2026-04-07T09:42:24.29186Z","steps":["trace[1477206285] 'agreement among raft nodes before linearized reading' (duration: 1.449689251s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:24.291925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:22.841907Z","time spent":"1.450009601s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T09:42:44.690668Z","caller":"traceutil/trace.go:171","msg":"trace[310984670] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"236.790817ms","start":"2026-04-07T09:42:44.453842Z","end":"2026-04-07T09:42:44.690633Z","steps":["trace[310984670] 'process raft request' (duration: 177.463946ms)","trace[310984670] 'compare' (duration: 59.140985ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:44.690865Z","caller":"traceutil/trace.go:171","msg":"trace[427072575] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"192.969256ms","start":"2026-04-07T09:42:44.497873Z","end":"2026-04-07T09:42:44.690842Z","steps":["trace[427072575] 'read index received' (duration: 133.386977ms)","trace[427072575] 'applied index is now lower than readState.Index' (duration: 59.574418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:44.691088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.196103ms","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-04-07T09:42:44.691101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.142083ms","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-04-07T09:42:44.691125Z","caller":"traceutil/trace.go:171","msg":"trace[361327923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"193.284195ms","start":"2026-04-07T09:42:44.49783Z","end":"2026-04-07T09:42:44.691115Z","steps":["trace[361327923] 'agreement among raft nodes before linearized reading' (duration: 193.099359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:44.69114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.894313ms","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-04-07T09:42:44.691155Z","caller":"traceutil/trace.go:171","msg":"trace[1549829136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"149.240356ms","start":"2026-04-07T09:42:44.541907Z","end":"2026-04-07T09:42:44.691147Z","steps":["trace[1549829136] 'agreement among raft nodes before linearized reading' (duration: 149.107242ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:44.691174Z","caller":"traceutil/trace.go:171","msg":"trace[290093471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"133.958945ms","start":"2026-04-07T09:42:44.557205Z","end":"2026-04-07T09:42:44.691164Z","steps":["trace[290093471] 'agreement among raft nodes before linearized reading' (duration: 133.874012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:46.766259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.474432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:42:46.766452Z","caller":"traceutil/trace.go:171","msg":"trace[939519658] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1083; }","duration":"474.71845ms","start":"2026-04-07T09:42:46.291712Z","end":"2026-04-07T09:42:46.76643Z","steps":["trace[939519658] 'count revisions from in-memory index tree' (duration: 474.39401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:46.766702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.291693Z","time spent":"474.991039ms","remote":"127.0.0.1:43892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:46.766836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.092704ms","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-04-07T09:42:46.766979Z","caller":"traceutil/trace.go:171","msg":"trace[1532463274] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1083; }","duration":"437.293169ms","start":"2026-04-07T09:42:46.329603Z","end":"2026-04-07T09:42:46.766896Z","steps":["trace[1532463274] 'count revisions from in-memory index tree' (duration: 437.010101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:46.767114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.329586Z","time spent":"437.511977ms","remote":"127.0.0.1:60932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:42:47.070679Z","caller":"traceutil/trace.go:171","msg":"trace[1955734620] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"375.639945ms","start":"2026-04-07T09:42:46.695015Z","end":"2026-04-07T09:42:47.070655Z","steps":["trace[1955734620] 'read index received' (duration: 375.413288ms)","trace[1955734620] 'applied index is now lower than readState.Index' (duration: 225.547µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:47.070845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.044719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:47.07087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.546728ms","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-04-07T09:42:47.070906Z","caller":"traceutil/trace.go:171","msg":"trace[1362150708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"340.628041ms","start":"2026-04-07T09:42:46.730267Z","end":"2026-04-07T09:42:47.070895Z","steps":["trace[1362150708] 'agreement among raft nodes before linearized reading' (duration: 340.508908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:47.070976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.73021Z","time spent":"340.758436ms","remote":"127.0.0.1:43762","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":"info","ts":"2026-04-07T09:42:47.070902Z","caller":"traceutil/trace.go:171","msg":"trace[1898669328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"155.134312ms","start":"2026-04-07T09:42:46.915754Z","end":"2026-04-07T09:42:47.070888Z","steps":["trace[1898669328] 'agreement among raft nodes before linearized reading' (duration: 155.038809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:47.071097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.09539ms","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-04-07T09:42:47.071138Z","caller":"traceutil/trace.go:171","msg":"trace[173995529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"376.175512ms","start":"2026-04-07T09:42:46.694951Z","end":"2026-04-07T09:42:47.071127Z","steps":["trace[173995529] 'agreement among raft nodes before linearized reading' (duration: 376.036597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:47.07117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.694902Z","time spent":"376.260925ms","remote":"127.0.0.1:43762","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-04-07T09:42:47.071286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.792024ms","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-04-07T09:42:47.071409Z","caller":"traceutil/trace.go:171","msg":"trace[132656428] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"615.139338ms","start":"2026-04-07T09:42:46.455578Z","end":"2026-04-07T09:42:47.070717Z","steps":["trace[132656428] 'process raft request' (duration: 614.936421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:47.071605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.455551Z","time spent":"615.929473ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:47.071837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.474195ms","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-04-07T09:42:47.071445Z","caller":"traceutil/trace.go:171","msg":"trace[1206119832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"339.066742ms","start":"2026-04-07T09:42:46.732357Z","end":"2026-04-07T09:42:47.071424Z","steps":["trace[1206119832] 'agreement among raft nodes before linearized reading' (duration: 338.485174ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:47.071936Z","caller":"traceutil/trace.go:171","msg":"trace[1441212696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"339.60479ms","start":"2026-04-07T09:42:46.732305Z","end":"2026-04-07T09:42:47.07191Z","steps":["trace[1441212696] 'agreement among raft nodes before linearized reading' (duration: 338.632489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:47.072008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.732295Z","time spent":"339.703292ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T09:42:47.072086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:46.732336Z","time spent":"339.678152ms","remote":"127.0.0.1:43762","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-04-07T09:42:47.23549Z","caller":"traceutil/trace.go:171","msg":"trace[1028499494] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"158.312192ms","start":"2026-04-07T09:42:47.077148Z","end":"2026-04-07T09:42:47.23546Z","steps":["trace[1028499494] 'process raft request' (duration: 158.267881ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:47.235562Z","caller":"traceutil/trace.go:171","msg":"trace[1588130841] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"159.055196ms","start":"2026-04-07T09:42:47.076469Z","end":"2026-04-07T09:42:47.235524Z","steps":["trace[1588130841] 'process raft request' (duration: 158.703485ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:47.235588Z","caller":"traceutil/trace.go:171","msg":"trace[1023651494] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"158.768326ms","start":"2026-04-07T09:42:47.076796Z","end":"2026-04-07T09:42:47.235564Z","steps":["trace[1023651494] 'process raft request' (duration: 158.56701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:48.842744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807269681,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:42:49.039909Z","caller":"traceutil/trace.go:171","msg":"trace[1379058536] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"698.122205ms","start":"2026-04-07T09:42:48.341766Z","end":"2026-04-07T09:42:49.039888Z","steps":["trace[1379058536] 'read index received' (duration: 697.894738ms)","trace[1379058536] 'applied index is now lower than readState.Index' (duration: 226.457µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:49.03996Z","caller":"traceutil/trace.go:171","msg":"trace[1301881638] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"925.820304ms","start":"2026-04-07T09:42:48.114113Z","end":"2026-04-07T09:42:49.039934Z","steps":["trace[1301881638] 'process raft request' (duration: 925.635688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:49.040073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.367248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:49.040098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:48.114101Z","time spent":"925.917038ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:49.040088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.284049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:42:49.040124Z","caller":"traceutil/trace.go:171","msg":"trace[690930891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"682.456221ms","start":"2026-04-07T09:42:48.357653Z","end":"2026-04-07T09:42:49.04011Z","steps":["trace[690930891] 'agreement among raft nodes before linearized reading' (duration: 682.366898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:49.040174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:48.357641Z","time spent":"682.522132ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:42:49.040167Z","caller":"traceutil/trace.go:171","msg":"trace[383083220] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1089; }","duration":"698.434134ms","start":"2026-04-07T09:42:48.341714Z","end":"2026-04-07T09:42:49.040148Z","steps":["trace[383083220] 'agreement among raft nodes before linearized reading' (duration: 698.265129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:49.040283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:48.34169Z","time spent":"698.575019ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-07T09:42:49.182526Z","caller":"traceutil/trace.go:171","msg":"trace[1058742431] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"106.193739ms","start":"2026-04-07T09:42:49.076304Z","end":"2026-04-07T09:42:49.182498Z","steps":["trace[1058742431] 'process raft request' (duration: 95.880284ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:49.349068Z","caller":"traceutil/trace.go:171","msg":"trace[2006400196] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"104.67296ms","start":"2026-04-07T09:42:49.244365Z","end":"2026-04-07T09:42:49.349038Z","steps":["trace[2006400196] 'process raft request' (duration: 104.35618ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:49.349241Z","caller":"traceutil/trace.go:171","msg":"trace[568810852] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"104.627789ms","start":"2026-04-07T09:42:49.244599Z","end":"2026-04-07T09:42:49.349226Z","steps":["trace[568810852] 'process raft request' (duration: 104.32731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.541068Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.14766609s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T09:42:52.544676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151193522s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:42:52.544991Z","caller":"traceutil/trace.go:171","msg":"trace[384447018] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1140; }","duration":"1.344224799s","start":"2026-04-07T09:42:51.200589Z","end":"2026-04-07T09:42:52.544814Z","steps":["trace[384447018] 'read index received' (duration: 192.8113ms)","trace[384447018] 'applied index is now lower than readState.Index' (duration: 1.151412679s)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:52.545011Z","caller":"traceutil/trace.go:171","msg":"trace[581550650] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"1.497065139s","start":"2026-04-07T09:42:51.047919Z","end":"2026-04-07T09:42:52.544984Z","steps":["trace[581550650] 'process raft request' (duration: 345.497785ms)","trace[581550650] 'compare' (duration: 1.151009966s)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:42:52.54506Z","caller":"traceutil/trace.go:171","msg":"trace[2030480177] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"1.358228001s","start":"2026-04-07T09:42:51.186814Z","end":"2026-04-07T09:42:52.545042Z","steps":["trace[2030480177] 'process raft request' (duration: 1.357957352s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.545106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.186798Z","time spent":"1.358282302s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:52.545137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.047897Z","time spent":"1.497171772s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:52.545296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344720425s","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-04-07T09:42:52.545315Z","caller":"traceutil/trace.go:171","msg":"trace[232724663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"1.344780636s","start":"2026-04-07T09:42:51.200528Z","end":"2026-04-07T09:42:52.545309Z","steps":["trace[232724663] 'agreement among raft nodes before linearized reading' (duration: 1.344698224s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.545332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.200499Z","time spent":"1.344828488s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T09:42:52.871514Z","caller":"traceutil/trace.go:171","msg":"trace[1793392675] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"326.471395ms","start":"2026-04-07T09:42:52.54501Z","end":"2026-04-07T09:42:52.871482Z","steps":["trace[1793392675] 'read index received' (duration: 326.32292ms)","trace[1793392675] 'applied index is now lower than readState.Index' (duration: 146.735µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:42:52.87159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51232646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:42:52.871632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518056421s","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-04-07T09:42:52.871665Z","caller":"traceutil/trace.go:171","msg":"trace[1493377072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"1.512444453s","start":"2026-04-07T09:42:51.359204Z","end":"2026-04-07T09:42:52.871648Z","steps":["trace[1493377072] 'agreement among raft nodes before linearized reading' (duration: 1.512301098s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.871728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.091904ms","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":"warn","ts":"2026-04-07T09:42:52.871733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529687197s","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-04-07T09:42:52.871709Z","caller":"traceutil/trace.go:171","msg":"trace[763104402] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"1.441547597s","start":"2026-04-07T09:42:51.430141Z","end":"2026-04-07T09:42:52.871689Z","steps":["trace[763104402] 'process raft request' (duration: 1.441197186s)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:52.871768Z","caller":"traceutil/trace.go:171","msg":"trace[610315834] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1098; }","duration":"614.131235ms","start":"2026-04-07T09:42:52.257626Z","end":"2026-04-07T09:42:52.871757Z","steps":["trace[610315834] 'agreement among raft nodes before linearized reading' (duration: 614.074224ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:42:52.871784Z","caller":"traceutil/trace.go:171","msg":"trace[1968113132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"1.52977661s","start":"2026-04-07T09:42:51.341992Z","end":"2026-04-07T09:42:52.871769Z","steps":["trace[1968113132] 'agreement among raft nodes before linearized reading' (duration: 1.529634645s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.871804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:52.257607Z","time spent":"614.186938ms","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:52.871784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518388061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T09:42:52.871845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.341965Z","time spent":"1.529848982s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T09:42:52.871854Z","caller":"traceutil/trace.go:171","msg":"trace[2016728610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.518488824s","start":"2026-04-07T09:42:51.353349Z","end":"2026-04-07T09:42:52.871838Z","steps":["trace[2016728610] 'agreement among raft nodes before linearized reading' (duration: 1.518237256s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.871862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.430121Z","time spent":"1.441665061s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:42:52.87172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.359189Z","time spent":"1.512522336s","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:42:52.871897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.35334Z","time spent":"1.518548785s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T09:42:52.871675Z","caller":"traceutil/trace.go:171","msg":"trace[896183595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"1.518144063s","start":"2026-04-07T09:42:51.35352Z","end":"2026-04-07T09:42:52.871664Z","steps":["trace[896183595] 'agreement among raft nodes before linearized reading' (duration: 1.518004049s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.871992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:51.353499Z","time spent":"1.518481053s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T09:42:52.882004Z","caller":"traceutil/trace.go:171","msg":"trace[1862656780] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"330.946746ms","start":"2026-04-07T09:42:52.551041Z","end":"2026-04-07T09:42:52.881987Z","steps":["trace[1862656780] 'process raft request' (duration: 330.844773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.882034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.067634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:42:52.882069Z","caller":"traceutil/trace.go:171","msg":"trace[983308539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1099; }","duration":"321.140716ms","start":"2026-04-07T09:42:52.56092Z","end":"2026-04-07T09:42:52.88206Z","steps":["trace[983308539] 'agreement among raft nodes before linearized reading' (duration: 321.064534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:42:52.882091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:52.5609Z","time spent":"321.185268ms","remote":"127.0.0.1:60908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:42:52.882111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:42:52.550999Z","time spent":"331.048939ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:01.297982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.495083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:43:01.29823Z","caller":"traceutil/trace.go:171","msg":"trace[1950287224] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1173; }","duration":"130.666291ms","start":"2026-04-07T09:43:01.167545Z","end":"2026-04-07T09:43:01.298211Z","steps":["trace[1950287224] 'read index received' (duration: 20.730914ms)","trace[1950287224] 'applied index is now lower than readState.Index' (duration: 109.934237ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:01.298243Z","caller":"traceutil/trace.go:171","msg":"trace[961024561] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"213.229494ms","start":"2026-04-07T09:43:01.084986Z","end":"2026-04-07T09:43:01.298215Z","steps":["trace[961024561] 'process raft request' (duration: 103.38341ms)","trace[961024561] 'compare' (duration: 109.361328ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:01.29834Z","caller":"traceutil/trace.go:171","msg":"trace[733024803] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"212.919464ms","start":"2026-04-07T09:43:01.08539Z","end":"2026-04-07T09:43:01.298309Z","steps":["trace[733024803] 'process raft request' (duration: 212.756539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:01.298482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.938059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-07T09:43:01.298531Z","caller":"traceutil/trace.go:171","msg":"trace[770122269] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1128; }","duration":"131.022922ms","start":"2026-04-07T09:43:01.167487Z","end":"2026-04-07T09:43:01.29851Z","steps":["trace[770122269] 'agreement among raft nodes before linearized reading' (duration: 130.781134ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:04.887532Z","caller":"traceutil/trace.go:171","msg":"trace[1882969533] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"274.878118ms","start":"2026-04-07T09:43:04.612627Z","end":"2026-04-07T09:43:04.887505Z","steps":["trace[1882969533] 'read index received' (duration: 274.61175ms)","trace[1882969533] 'applied index is now lower than readState.Index' (duration: 265.308µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:04.887629Z","caller":"traceutil/trace.go:171","msg":"trace[69143622] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"289.288102ms","start":"2026-04-07T09:43:04.598312Z","end":"2026-04-07T09:43:04.8876Z","steps":["trace[69143622] 'process raft request' (duration: 289.029664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:04.887731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.084835ms","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-04-07T09:43:04.887759Z","caller":"traceutil/trace.go:171","msg":"trace[979169095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1187; }","duration":"275.155967ms","start":"2026-04-07T09:43:04.612592Z","end":"2026-04-07T09:43:04.887748Z","steps":["trace[979169095] 'agreement among raft nodes before linearized reading' (duration: 275.008112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.394237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807269917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T09:43:07.478724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070787695s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T09:43:07.485947Z","caller":"traceutil/trace.go:171","msg":"trace[787300231] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1242; }","duration":"592.501054ms","start":"2026-04-07T09:43:06.893419Z","end":"2026-04-07T09:43:07.48592Z","steps":["trace[787300231] 'read index received' (duration: 585.478892ms)","trace[787300231] 'applied index is now lower than readState.Index' (duration: 7.021591ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:07.48601Z","caller":"traceutil/trace.go:171","msg":"trace[1892078564] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"593.931529ms","start":"2026-04-07T09:43:06.892073Z","end":"2026-04-07T09:43:07.486005Z","steps":["trace[1892078564] 'process raft request' (duration: 593.774914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.486078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:06.892056Z","time spent":"593.97162ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:07.486226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.804633ms","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-04-07T09:43:07.486255Z","caller":"traceutil/trace.go:171","msg":"trace[118923303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"592.848665ms","start":"2026-04-07T09:43:06.8934Z","end":"2026-04-07T09:43:07.486249Z","steps":["trace[118923303] 'agreement among raft nodes before linearized reading' (duration: 592.748332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.486278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:06.893394Z","time spent":"592.879326ms","remote":"127.0.0.1:43762","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-04-07T09:43:07.486428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.601188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T09:43:07.486508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.13624ms","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-04-07T09:43:07.486541Z","caller":"traceutil/trace.go:171","msg":"trace[994615082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"167.194342ms","start":"2026-04-07T09:43:07.319338Z","end":"2026-04-07T09:43:07.486532Z","steps":["trace[994615082] 'agreement among raft nodes before linearized reading' (duration: 167.12818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.48658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.280676ms","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-04-07T09:43:07.486629Z","caller":"traceutil/trace.go:171","msg":"trace[1838543194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"324.349518ms","start":"2026-04-07T09:43:07.162269Z","end":"2026-04-07T09:43:07.486619Z","steps":["trace[1838543194] 'agreement among raft nodes before linearized reading' (duration: 324.255285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.486689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.310103ms","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-04-07T09:43:07.486718Z","caller":"traceutil/trace.go:171","msg":"trace[1548580114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"165.354725ms","start":"2026-04-07T09:43:07.321357Z","end":"2026-04-07T09:43:07.486712Z","steps":["trace[1548580114] 'agreement among raft nodes before linearized reading' (duration: 165.283342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.486714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:07.162255Z","time spent":"324.40326ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T09:43:07.486502Z","caller":"traceutil/trace.go:171","msg":"trace[889267754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"495.700151ms","start":"2026-04-07T09:43:06.990784Z","end":"2026-04-07T09:43:07.486484Z","steps":["trace[889267754] 'agreement among raft nodes before linearized reading' (duration: 495.529526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:07.486782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:06.990771Z","time spent":"495.99126ms","remote":"127.0.0.1:43762","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-04-07T09:43:17.653193Z","caller":"traceutil/trace.go:171","msg":"trace[1056356651] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"119.669154ms","start":"2026-04-07T09:43:17.533501Z","end":"2026-04-07T09:43:17.65317Z","steps":["trace[1056356651] 'read index received' (duration: 119.416856ms)","trace[1056356651] 'applied index is now lower than readState.Index' (duration: 249.138µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:17.653317Z","caller":"traceutil/trace.go:171","msg":"trace[1287378725] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"138.5848ms","start":"2026-04-07T09:43:17.51467Z","end":"2026-04-07T09:43:17.653255Z","steps":["trace[1287378725] 'process raft request' (duration: 138.337633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:17.653411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.905311ms","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-04-07T09:43:17.653452Z","caller":"traceutil/trace.go:171","msg":"trace[556569306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"120.011124ms","start":"2026-04-07T09:43:17.533429Z","end":"2026-04-07T09:43:17.65344Z","steps":["trace[556569306] 'agreement among raft nodes before linearized reading' (duration: 119.87006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:17.653496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.875164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T09:43:17.653497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.867236ms","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-04-07T09:43:17.653567Z","caller":"traceutil/trace.go:171","msg":"trace[90634593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"115.997888ms","start":"2026-04-07T09:43:17.537552Z","end":"2026-04-07T09:43:17.653549Z","steps":["trace[90634593] 'agreement among raft nodes before linearized reading' (duration: 115.822172ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:17.653572Z","caller":"traceutil/trace.go:171","msg":"trace[535559773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"110.98886ms","start":"2026-04-07T09:43:17.542567Z","end":"2026-04-07T09:43:17.653556Z","steps":["trace[535559773] 'agreement among raft nodes before linearized reading' (duration: 110.810124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:17.653672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.57959ms","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-04-07T09:43:17.653706Z","caller":"traceutil/trace.go:171","msg":"trace[990291331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"112.642472ms","start":"2026-04-07T09:43:17.541053Z","end":"2026-04-07T09:43:17.653696Z","steps":["trace[990291331] 'agreement among raft nodes before linearized reading' (duration: 112.546649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:17.653709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.285423ms","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-04-07T09:43:17.653745Z","caller":"traceutil/trace.go:171","msg":"trace[1036703086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"113.357395ms","start":"2026-04-07T09:43:17.540377Z","end":"2026-04-07T09:43:17.653734Z","steps":["trace[1036703086] 'agreement among raft nodes before linearized reading' (duration: 113.276812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:21.975853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.356975ms","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-04-07T09:43:21.975911Z","caller":"traceutil/trace.go:171","msg":"trace[1091718957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"296.474119ms","start":"2026-04-07T09:43:21.679426Z","end":"2026-04-07T09:43:21.9759Z","steps":["trace[1091718957] 'range keys from in-memory index tree' (duration: 296.228411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:21.97599Z","caller":"traceutil/trace.go:171","msg":"trace[1842150089] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"296.331394ms","start":"2026-04-07T09:43:21.679635Z","end":"2026-04-07T09:43:21.975967Z","steps":["trace[1842150089] 'read index received' (duration: 212.079797ms)","trace[1842150089] 'applied index is now lower than readState.Index' (duration: 84.250187ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:21.976002Z","caller":"traceutil/trace.go:171","msg":"trace[706111162] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"296.476049ms","start":"2026-04-07T09:43:21.679493Z","end":"2026-04-07T09:43:21.975969Z","steps":["trace[706111162] 'process raft request' (duration: 212.183111ms)","trace[706111162] 'compare' (duration: 84.112923ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:21.976125Z","caller":"traceutil/trace.go:171","msg":"trace[425057760] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"295.56633ms","start":"2026-04-07T09:43:21.680547Z","end":"2026-04-07T09:43:21.976113Z","steps":["trace[425057760] 'process raft request' (duration: 295.378484ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:21.976255Z","caller":"traceutil/trace.go:171","msg":"trace[269571214] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"294.647441ms","start":"2026-04-07T09:43:21.681576Z","end":"2026-04-07T09:43:21.976223Z","steps":["trace[269571214] 'process raft request' (duration: 294.501787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:21.976451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.800339ms","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-04-07T09:43:21.976493Z","caller":"traceutil/trace.go:171","msg":"trace[931748536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"296.885542ms","start":"2026-04-07T09:43:21.6796Z","end":"2026-04-07T09:43:21.976485Z","steps":["trace[931748536] 'agreement among raft nodes before linearized reading' (duration: 296.449038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.175284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.063821ms","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-04-07T09:43:24.175647Z","caller":"traceutil/trace.go:171","msg":"trace[393209371] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1273; }","duration":"606.501746ms","start":"2026-04-07T09:43:23.569127Z","end":"2026-04-07T09:43:24.175629Z","steps":["trace[393209371] 'count revisions from in-memory index tree' (duration: 605.927898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.175696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.569103Z","time spent":"606.576998ms","remote":"127.0.0.1:60806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:43:24.328123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225338807270089,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T09:43:24.352596Z","caller":"traceutil/trace.go:171","msg":"trace[1041938861] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"524.754637ms","start":"2026-04-07T09:43:23.827819Z","end":"2026-04-07T09:43:24.352574Z","steps":["trace[1041938861] 'read index received' (duration: 524.463738ms)","trace[1041938861] 'applied index is now lower than readState.Index' (duration: 289.919µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:24.352652Z","caller":"traceutil/trace.go:171","msg":"trace[1765292203] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"677.239376ms","start":"2026-04-07T09:43:23.675376Z","end":"2026-04-07T09:43:24.352616Z","steps":["trace[1765292203] 'process raft request' (duration: 677.027299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.352812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.675352Z","time spent":"677.36483ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:24.352992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.14481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:43:24.353039Z","caller":"traceutil/trace.go:171","msg":"trace[42611879] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1274; }","duration":"525.276674ms","start":"2026-04-07T09:43:23.827752Z","end":"2026-04-07T09:43:24.353029Z","steps":["trace[42611879] 'agreement among raft nodes before linearized reading' (duration: 524.942963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.35305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.394382ms","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-04-07T09:43:24.353093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.827549Z","time spent":"525.531442ms","remote":"127.0.0.1:43822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-07T09:43:24.353076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.639608ms","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-04-07T09:43:24.3531Z","caller":"traceutil/trace.go:171","msg":"trace[1125038972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"371.512745ms","start":"2026-04-07T09:43:23.981573Z","end":"2026-04-07T09:43:24.353085Z","steps":["trace[1125038972] 'agreement among raft nodes before linearized reading' (duration: 371.364331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.353087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.582271ms","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-04-07T09:43:24.353145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.981529Z","time spent":"371.608548ms","remote":"127.0.0.1:43762","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":"info","ts":"2026-04-07T09:43:24.353159Z","caller":"traceutil/trace.go:171","msg":"trace[138731604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"367.699125ms","start":"2026-04-07T09:43:23.985444Z","end":"2026-04-07T09:43:24.353143Z","steps":["trace[138731604] 'agreement among raft nodes before linearized reading' (duration: 367.52176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:24.353171Z","caller":"traceutil/trace.go:171","msg":"trace[315252161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"370.792153ms","start":"2026-04-07T09:43:23.982362Z","end":"2026-04-07T09:43:24.353154Z","steps":["trace[315252161] 'agreement among raft nodes before linearized reading' (duration: 370.537875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.353219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.985437Z","time spent":"367.767997ms","remote":"127.0.0.1:43762","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-04-07T09:43:24.353245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.982349Z","time spent":"370.860984ms","remote":"127.0.0.1:43762","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-04-07T09:43:24.35346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.089046ms","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-04-07T09:43:24.353502Z","caller":"traceutil/trace.go:171","msg":"trace[594365353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"374.170969ms","start":"2026-04-07T09:43:23.979321Z","end":"2026-04-07T09:43:24.353492Z","steps":["trace[594365353] 'agreement among raft nodes before linearized reading' (duration: 373.425336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.35337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.109759ms","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-04-07T09:43:24.353789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.979298Z","time spent":"374.415606ms","remote":"127.0.0.1:43762","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-04-07T09:43:24.353817Z","caller":"traceutil/trace.go:171","msg":"trace[179642227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"369.597875ms","start":"2026-04-07T09:43:23.984204Z","end":"2026-04-07T09:43:24.353802Z","steps":["trace[179642227] 'agreement among raft nodes before linearized reading' (duration: 368.598803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:24.354117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:23.98418Z","time spent":"369.890125ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T09:43:24.604349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.242574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:43:24.604625Z","caller":"traceutil/trace.go:171","msg":"trace[139362241] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"245.020047ms","start":"2026-04-07T09:43:24.359581Z","end":"2026-04-07T09:43:24.604601Z","steps":["trace[139362241] 'process raft request' (duration: 244.863882ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:24.604611Z","caller":"traceutil/trace.go:171","msg":"trace[1183908694] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"245.194452ms","start":"2026-04-07T09:43:24.359333Z","end":"2026-04-07T09:43:24.604528Z","steps":["trace[1183908694] 'process raft request' (duration: 103.704531ms)","trace[1183908694] 'compare' (duration: 141.146291ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:24.604967Z","caller":"traceutil/trace.go:171","msg":"trace[644404056] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1327; }","duration":"244.007625ms","start":"2026-04-07T09:43:24.360951Z","end":"2026-04-07T09:43:24.604959Z","steps":["trace[644404056] 'read index received' (duration: 102.10351ms)","trace[644404056] 'applied index is now lower than readState.Index' (duration: 141.903365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:24.605096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.137369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:24.605126Z","caller":"traceutil/trace.go:171","msg":"trace[493270656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"244.18386ms","start":"2026-04-07T09:43:24.360935Z","end":"2026-04-07T09:43:24.605119Z","steps":["trace[493270656] 'agreement among raft nodes before linearized reading' (duration: 244.061226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:24.60525Z","caller":"traceutil/trace.go:171","msg":"trace[1237973542] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"244.78896ms","start":"2026-04-07T09:43:24.360443Z","end":"2026-04-07T09:43:24.605232Z","steps":["trace[1237973542] 'process raft request' (duration: 244.439219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:24.605296Z","caller":"traceutil/trace.go:171","msg":"trace[1841474934] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"242.249489ms","start":"2026-04-07T09:43:24.363036Z","end":"2026-04-07T09:43:24.605285Z","steps":["trace[1841474934] 'process raft request' (duration: 241.897568ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:24.605416Z","caller":"traceutil/trace.go:171","msg":"trace[57713064] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"245.727979ms","start":"2026-04-07T09:43:24.359672Z","end":"2026-04-07T09:43:24.6054Z","steps":["trace[57713064] 'process raft request' (duration: 244.80801ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:25.771002Z","caller":"traceutil/trace.go:171","msg":"trace[779590625] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"133.835251ms","start":"2026-04-07T09:43:25.637143Z","end":"2026-04-07T09:43:25.770978Z","steps":["trace[779590625] 'process raft request' (duration: 133.654355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:26.316867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.605236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:43:26.317055Z","caller":"traceutil/trace.go:171","msg":"trace[274547843] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"567.221087ms","start":"2026-04-07T09:43:25.749816Z","end":"2026-04-07T09:43:26.317037Z","steps":["trace[274547843] 'process raft request' (duration: 229.385223ms)","trace[274547843] 'compare' (duration: 337.160852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:26.317128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:25.749794Z","time spent":"567.308039ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:26.42323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.268291ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-07T09:43:26.500175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.365934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:26.500262Z","caller":"traceutil/trace.go:171","msg":"trace[491504274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"132.505598ms","start":"2026-04-07T09:43:26.367738Z","end":"2026-04-07T09:43:26.500244Z","steps":["trace[491504274] 'agreement among raft nodes before linearized reading' (duration: 56.010686ms)","trace[491504274] 'range keys from in-memory index tree' (duration: 76.342547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:26.500205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.779839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:43:26.500543Z","caller":"traceutil/trace.go:171","msg":"trace[2010890897] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1282; }","duration":"134.167831ms","start":"2026-04-07T09:43:26.366365Z","end":"2026-04-07T09:43:26.500533Z","steps":["trace[2010890897] 'agreement among raft nodes before linearized reading' (duration: 57.39769ms)","trace[2010890897] 'count revisions from in-memory index tree' (duration: 76.40131ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:26.885809Z","caller":"traceutil/trace.go:171","msg":"trace[1889221175] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"271.454789ms","start":"2026-04-07T09:43:26.614328Z","end":"2026-04-07T09:43:26.885783Z","steps":["trace[1889221175] 'process raft request' (duration: 271.257173ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:26.885987Z","caller":"traceutil/trace.go:171","msg":"trace[1522960575] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"270.827279ms","start":"2026-04-07T09:43:26.615144Z","end":"2026-04-07T09:43:26.885971Z","steps":["trace[1522960575] 'process raft request' (duration: 270.556371ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:26.886108Z","caller":"traceutil/trace.go:171","msg":"trace[1451806369] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"269.847558ms","start":"2026-04-07T09:43:26.616253Z","end":"2026-04-07T09:43:26.886101Z","steps":["trace[1451806369] 'process raft request' (duration: 269.700524ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:26.926949Z","caller":"traceutil/trace.go:171","msg":"trace[943803292] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"153.714767ms","start":"2026-04-07T09:43:26.773181Z","end":"2026-04-07T09:43:26.926896Z","steps":["trace[943803292] 'process raft request' (duration: 153.652875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:26.927455Z","caller":"traceutil/trace.go:171","msg":"trace[1284711527] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"227.417991ms","start":"2026-04-07T09:43:26.699991Z","end":"2026-04-07T09:43:26.927409Z","steps":["trace[1284711527] 'process raft request' (duration: 226.356857ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:28.253961Z","caller":"traceutil/trace.go:171","msg":"trace[107520078] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"223.174887ms","start":"2026-04-07T09:43:28.030764Z","end":"2026-04-07T09:43:28.253938Z","steps":["trace[107520078] 'process raft request' (duration: 174.581585ms)","trace[107520078] 'compare' (duration: 48.487439ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:37.275614Z","caller":"traceutil/trace.go:171","msg":"trace[1770515350] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"359.576889ms","start":"2026-04-07T09:43:36.916012Z","end":"2026-04-07T09:43:37.275589Z","steps":["trace[1770515350] 'read index received' (duration: 359.383373ms)","trace[1770515350] 'applied index is now lower than readState.Index' (duration: 192.696µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:37.275766Z","caller":"traceutil/trace.go:171","msg":"trace[956685432] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"535.377532ms","start":"2026-04-07T09:43:36.740332Z","end":"2026-04-07T09:43:37.275709Z","steps":["trace[956685432] 'process raft request' (duration: 535.110764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.275888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.831387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:37.275951Z","caller":"traceutil/trace.go:171","msg":"trace[1289819022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"359.988602ms","start":"2026-04-07T09:43:36.915948Z","end":"2026-04-07T09:43:37.275937Z","steps":["trace[1289819022] 'agreement among raft nodes before linearized reading' (duration: 359.850887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.276027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.915924Z","time spent":"360.090955ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:43:37.276123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.493997ms","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-04-07T09:43:37.276185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.525779ms","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-04-07T09:43:37.276225Z","caller":"traceutil/trace.go:171","msg":"trace[985919247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"347.641893ms","start":"2026-04-07T09:43:36.928557Z","end":"2026-04-07T09:43:37.276199Z","steps":["trace[985919247] 'agreement among raft nodes before linearized reading' (duration: 347.406715ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:37.276258Z","caller":"traceutil/trace.go:171","msg":"trace[306490805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"308.597801ms","start":"2026-04-07T09:43:36.96762Z","end":"2026-04-07T09:43:37.276218Z","steps":["trace[306490805] 'agreement among raft nodes before linearized reading' (duration: 308.339403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.276284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.928537Z","time spent":"347.736515ms","remote":"127.0.0.1:43762","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-04-07T09:43:37.276295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.96761Z","time spent":"308.676134ms","remote":"127.0.0.1:43762","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-04-07T09:43:37.276453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.782564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-07T09:43:37.276539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.866377ms","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-04-07T09:43:37.276586Z","caller":"traceutil/trace.go:171","msg":"trace[278003282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"345.95635ms","start":"2026-04-07T09:43:36.930616Z","end":"2026-04-07T09:43:37.276573Z","steps":["trace[278003282] 'agreement among raft nodes before linearized reading' (duration: 345.822555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.276667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.930568Z","time spent":"346.087463ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T09:43:37.276649Z","caller":"traceutil/trace.go:171","msg":"trace[1716200295] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"299.98893ms","start":"2026-04-07T09:43:36.976574Z","end":"2026-04-07T09:43:37.276562Z","steps":["trace[1716200295] 'agreement among raft nodes before linearized reading' (duration: 299.475604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.276813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.976559Z","time spent":"300.239258ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-07T09:43:37.276816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.740312Z","time spent":"535.548098ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:37.277105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.736123ms","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-04-07T09:43:37.277334Z","caller":"traceutil/trace.go:171","msg":"trace[1228866939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"345.98426ms","start":"2026-04-07T09:43:36.931333Z","end":"2026-04-07T09:43:37.277317Z","steps":["trace[1228866939] 'agreement among raft nodes before linearized reading' (duration: 345.170414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.277447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:36.931326Z","time spent":"346.100833ms","remote":"127.0.0.1:43762","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-04-07T09:43:37.949496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.637879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:43:37.950423Z","caller":"traceutil/trace.go:171","msg":"trace[1489763109] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"668.665884ms","start":"2026-04-07T09:43:37.281741Z","end":"2026-04-07T09:43:37.950407Z","steps":["trace[1489763109] 'process raft request' (duration: 668.560101ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:37.950429Z","caller":"traceutil/trace.go:171","msg":"trace[1383971944] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"668.699816ms","start":"2026-04-07T09:43:37.2817Z","end":"2026-04-07T09:43:37.9504Z","steps":["trace[1383971944] 'process raft request' (duration: 67.042424ms)","trace[1383971944] 'compare' (duration: 600.253668ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:43:37.950507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.281719Z","time spent":"668.749217ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:37.950556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.281684Z","time spent":"668.795618ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:43:37.950696Z","caller":"traceutil/trace.go:171","msg":"trace[701056664] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"668.814599ms","start":"2026-04-07T09:43:37.281854Z","end":"2026-04-07T09:43:37.950669Z","steps":["trace[701056664] 'process raft request' (duration: 668.51773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.950808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.281844Z","time spent":"668.911622ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:43:37.951116Z","caller":"traceutil/trace.go:171","msg":"trace[136845471] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1412; }","duration":"593.649859ms","start":"2026-04-07T09:43:37.357445Z","end":"2026-04-07T09:43:37.951095Z","steps":["trace[136845471] 'read index received' (duration: 448.311246ms)","trace[136845471] 'applied index is now lower than readState.Index' (duration: 145.337613ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:43:37.951152Z","caller":"traceutil/trace.go:171","msg":"trace[1104646532] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"667.861239ms","start":"2026-04-07T09:43:37.283267Z","end":"2026-04-07T09:43:37.951128Z","steps":["trace[1104646532] 'process raft request' (duration: 667.336632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:43:37.951243Z","caller":"traceutil/trace.go:171","msg":"trace[1109842125] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"667.419735ms","start":"2026-04-07T09:43:37.283807Z","end":"2026-04-07T09:43:37.951227Z","steps":["trace[1109842125] 'process raft request' (duration: 667.224729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.951287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.283258Z","time spent":"667.956863ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:37.951339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.283797Z","time spent":"667.490568ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:43:37.951385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.939959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:43:37.95146Z","caller":"traceutil/trace.go:171","msg":"trace[750761878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"594.050722ms","start":"2026-04-07T09:43:37.357394Z","end":"2026-04-07T09:43:37.951445Z","steps":["trace[750761878] 'agreement among raft nodes before linearized reading' (duration: 593.954529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:37.951499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.357375Z","time spent":"594.116184ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T09:43:38.292542Z","caller":"traceutil/trace.go:171","msg":"trace[1360192487] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"335.925887ms","start":"2026-04-07T09:43:37.956599Z","end":"2026-04-07T09:43:38.292525Z","steps":["trace[1360192487] 'process raft request' (duration: 335.830224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:43:38.292798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:43:37.956583Z","time spent":"336.01127ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T09:44:04.327776Z","caller":"traceutil/trace.go:171","msg":"trace[1363567264] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"178.811952ms","start":"2026-04-07T09:44:04.14895Z","end":"2026-04-07T09:44:04.327762Z","steps":["trace[1363567264] 'process raft request' (duration: 178.397879ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:04.328095Z","caller":"traceutil/trace.go:171","msg":"trace[1116215638] linearizableReadLoop","detail":"{readStateIndex:1540; appliedIndex:1538; }","duration":"137.103944ms","start":"2026-04-07T09:44:04.190402Z","end":"2026-04-07T09:44:04.327506Z","steps":["trace[1116215638] 'read index received' (duration: 7.566179ms)","trace[1116215638] 'applied index is now lower than readState.Index' (duration: 129.536295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:44:04.328289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.860028ms","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-04-07T09:44:04.328382Z","caller":"traceutil/trace.go:171","msg":"trace[1853166463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1479; }","duration":"137.994902ms","start":"2026-04-07T09:44:04.190367Z","end":"2026-04-07T09:44:04.328362Z","steps":["trace[1853166463] 'agreement among raft nodes before linearized reading' (duration: 137.717033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:04.328411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.061354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T09:44:04.328459Z","caller":"traceutil/trace.go:171","msg":"trace[2146269762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1479; }","duration":"132.148777ms","start":"2026-04-07T09:44:04.196295Z","end":"2026-04-07T09:44:04.328443Z","steps":["trace[2146269762] 'agreement among raft nodes before linearized reading' (duration: 131.959481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:04.328493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.202653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-07T09:44:04.328571Z","caller":"traceutil/trace.go:171","msg":"trace[233136561] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1479; }","duration":"129.326618ms","start":"2026-04-07T09:44:04.199227Z","end":"2026-04-07T09:44:04.328553Z","steps":["trace[233136561] 'agreement among raft nodes before linearized reading' (duration: 129.08402ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:06.48543Z","caller":"traceutil/trace.go:171","msg":"trace[2042557515] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"100.9467ms","start":"2026-04-07T09:44:06.38443Z","end":"2026-04-07T09:44:06.485376Z","steps":["trace[2042557515] 'process raft request' (duration: 100.505267ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:06.488589Z","caller":"traceutil/trace.go:171","msg":"trace[885484588] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"103.663256ms","start":"2026-04-07T09:44:06.384904Z","end":"2026-04-07T09:44:06.488567Z","steps":["trace[885484588] 'process raft request' (duration: 103.521192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:06.488795Z","caller":"traceutil/trace.go:171","msg":"trace[1798747386] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"103.856933ms","start":"2026-04-07T09:44:06.384919Z","end":"2026-04-07T09:44:06.488776Z","steps":["trace[1798747386] 'process raft request' (duration: 103.606545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:08.553175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.78777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T09:44:08.553226Z","caller":"traceutil/trace.go:171","msg":"trace[2133246267] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1498; }","duration":"115.873453ms","start":"2026-04-07T09:44:08.43734Z","end":"2026-04-07T09:44:08.553214Z","steps":["trace[2133246267] 'range keys from in-memory index tree' (duration: 115.699597ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:10.511088Z","caller":"traceutil/trace.go:171","msg":"trace[1292162534] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"115.958465ms","start":"2026-04-07T09:44:10.395099Z","end":"2026-04-07T09:44:10.511057Z","steps":["trace[1292162534] 'process raft request' (duration: 115.771159ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:10.782499Z","caller":"traceutil/trace.go:171","msg":"trace[683678051] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"110.08354ms","start":"2026-04-07T09:44:10.672393Z","end":"2026-04-07T09:44:10.782477Z","steps":["trace[683678051] 'process raft request' (duration: 109.995877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:10.782504Z","caller":"traceutil/trace.go:171","msg":"trace[855288936] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"110.07767ms","start":"2026-04-07T09:44:10.672402Z","end":"2026-04-07T09:44:10.78248Z","steps":["trace[855288936] 'process raft request' (duration: 109.406489ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:10.78266Z","caller":"traceutil/trace.go:171","msg":"trace[677850188] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"110.200753ms","start":"2026-04-07T09:44:10.67245Z","end":"2026-04-07T09:44:10.782651Z","steps":["trace[677850188] 'process raft request' (duration: 109.985216ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:33.203948Z","caller":"traceutil/trace.go:171","msg":"trace[1778520674] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"116.534593ms","start":"2026-04-07T09:44:33.087321Z","end":"2026-04-07T09:44:33.203855Z","steps":["trace[1778520674] 'process raft request' (duration: 108.062596ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:43.109929Z","caller":"traceutil/trace.go:171","msg":"trace[1866329428] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"105.418823ms","start":"2026-04-07T09:44:43.004491Z","end":"2026-04-07T09:44:43.10991Z","steps":["trace[1866329428] 'process raft request' (duration: 61.022879ms)","trace[1866329428] 'compare' (duration: 44.329382ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:44.950876Z","caller":"traceutil/trace.go:171","msg":"trace[656315855] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"131.279349ms","start":"2026-04-07T09:44:44.819565Z","end":"2026-04-07T09:44:44.950844Z","steps":["trace[656315855] 'process raft request' (duration: 131.048882ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:45.097429Z","caller":"traceutil/trace.go:171","msg":"trace[227911336] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"120.634791ms","start":"2026-04-07T09:44:44.976764Z","end":"2026-04-07T09:44:45.097399Z","steps":["trace[227911336] 'process raft request' (duration: 99.260774ms)","trace[227911336] 'compare' (duration: 21.254973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:03.358273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.94766ms","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-04-07T09:45:03.358363Z","caller":"traceutil/trace.go:171","msg":"trace[266603851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1747; }","duration":"103.104165ms","start":"2026-04-07T09:45:03.255242Z","end":"2026-04-07T09:45:03.358346Z","steps":["trace[266603851] 'range keys from in-memory index tree' (duration: 102.740583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:45:03.603534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.075793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:45:03.603655Z","caller":"traceutil/trace.go:171","msg":"trace[1399570907] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"207.316967ms","start":"2026-04-07T09:45:03.396304Z","end":"2026-04-07T09:45:03.603621Z","steps":["trace[1399570907] 'process raft request' (duration: 75.074699ms)","trace[1399570907] 'compare' (duration: 131.959459ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:45:03.769872Z","caller":"traceutil/trace.go:171","msg":"trace[1297176543] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"110.527611ms","start":"2026-04-07T09:45:03.659314Z","end":"2026-04-07T09:45:03.769842Z","steps":["trace[1297176543] 'process raft request' (duration: 108.710334ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:03.962968Z","caller":"traceutil/trace.go:171","msg":"trace[1119128873] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"162.086065ms","start":"2026-04-07T09:45:03.800809Z","end":"2026-04-07T09:45:03.962895Z","steps":["trace[1119128873] 'process raft request' (duration: 130.141703ms)","trace[1119128873] 'compare' (duration: 31.733565ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:45:30.221552Z","caller":"traceutil/trace.go:171","msg":"trace[1494035335] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"162.056163ms","start":"2026-04-07T09:45:30.059477Z","end":"2026-04-07T09:45:30.221533Z","steps":["trace[1494035335] 'read index received' (duration: 161.883708ms)","trace[1494035335] 'applied index is now lower than readState.Index' (duration: 171.925µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:45:30.221696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.217148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-07T09:45:30.221718Z","caller":"traceutil/trace.go:171","msg":"trace[284148592] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1871; }","duration":"162.291091ms","start":"2026-04-07T09:45:30.05942Z","end":"2026-04-07T09:45:30.221712Z","steps":["trace[284148592] 'agreement among raft nodes before linearized reading' (duration: 162.175737ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:30.221865Z","caller":"traceutil/trace.go:171","msg":"trace[1053746191] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"282.689885ms","start":"2026-04-07T09:45:29.938894Z","end":"2026-04-07T09:45:30.221584Z","steps":["trace[1053746191] 'process raft request' (duration: 282.54035ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:53.567862Z","caller":"traceutil/trace.go:171","msg":"trace[437164506] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"104.970038ms","start":"2026-04-07T09:45:53.462871Z","end":"2026-04-07T09:45:53.567841Z","steps":["trace[437164506] 'process raft request' (duration: 104.851365ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:54.546179Z","caller":"traceutil/trace.go:171","msg":"trace[543587764] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"151.877906ms","start":"2026-04-07T09:45:54.394274Z","end":"2026-04-07T09:45:54.546152Z","steps":["trace[543587764] 'process raft request' (duration: 151.729571ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:46:25.613274Z","caller":"traceutil/trace.go:171","msg":"trace[1782855533] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"121.605114ms","start":"2026-04-07T09:46:25.491646Z","end":"2026-04-07T09:46:25.613251Z","steps":["trace[1782855533] 'process raft request' (duration: 121.46498ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:46:59.788447Z","caller":"traceutil/trace.go:171","msg":"trace[1615745453] linearizableReadLoop","detail":"{readStateIndex:2407; appliedIndex:2406; }","duration":"429.654143ms","start":"2026-04-07T09:46:59.358769Z","end":"2026-04-07T09:46:59.788423Z","steps":["trace[1615745453] 'read index received' (duration: 429.444057ms)","trace[1615745453] 'applied index is now lower than readState.Index' (duration: 208.896µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:46:59.788534Z","caller":"traceutil/trace.go:171","msg":"trace[970933338] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"492.279712ms","start":"2026-04-07T09:46:59.296216Z","end":"2026-04-07T09:46:59.788496Z","steps":["trace[970933338] 'process raft request' (duration: 492.065395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:59.788609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.827919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:46:59.788655Z","caller":"traceutil/trace.go:171","msg":"trace[1866118201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2310; }","duration":"429.931942ms","start":"2026-04-07T09:46:59.358712Z","end":"2026-04-07T09:46:59.788644Z","steps":["trace[1866118201] 'agreement among raft nodes before linearized reading' (duration: 429.84486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:59.788708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:46:59.358686Z","time spent":"430.013905ms","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T09:46:59.788703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:46:59.296191Z","time spent":"492.421326ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:46:59.789016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.64781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T09:46:59.789153Z","caller":"traceutil/trace.go:171","msg":"trace[98944202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2310; }","duration":"307.819625ms","start":"2026-04-07T09:46:59.481312Z","end":"2026-04-07T09:46:59.789132Z","steps":["trace[98944202] 'agreement among raft nodes before linearized reading' (duration: 307.507605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:59.789305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:46:59.481294Z","time spent":"307.99153ms","remote":"127.0.0.1:43762","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\" "}