{"level":"warn","ts":"2026-05-27T13:51:30.129652Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T13:51:30.129743Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.159: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.19.213.159:2380","--initial-cluster=instance=https://199.19.213.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.159:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T13:51:30.129814Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T13:51:30.129822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.159:2380"]} {"level":"info","ts":"2026-05-27T13:51:30.12984Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T13:51:30.130454Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"]} {"level":"info","ts":"2026-05-27T13:51:30.130526Z","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.19.213.159:2380"],"listen-peer-urls":["https://199.19.213.159:2380"],"advertise-client-urls":["https://199.19.213.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.159:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T13:51:30.135166Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.437543ms"} {"level":"info","ts":"2026-05-27T13:51:30.169032Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"63cd833aa17a09c7","cluster-id":"27437233b92849be"} {"level":"info","ts":"2026-05-27T13:51:30.169218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T13:51:30.169257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became follower at term 0"} {"level":"info","ts":"2026-05-27T13:51:30.169313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 63cd833aa17a09c7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T13:51:30.169327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became follower at term 1"} {"level":"info","ts":"2026-05-27T13:51:30.169401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=(7191548467821021639)"} {"level":"warn","ts":"2026-05-27T13:51:30.178524Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T13:51:30.180035Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T13:51:30.181377Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T13:51:30.18424Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"63cd833aa17a09c7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T13:51:30.184603Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T13:51:30.184794Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T13:51:30.184834Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T13:51:30.185038Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"63cd833aa17a09c7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T13:51:30.188314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=(7191548467821021639)"} {"level":"info","ts":"2026-05-27T13:51:30.18863Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27437233b92849be","local-member-id":"63cd833aa17a09c7","added-peer-id":"63cd833aa17a09c7","added-peer-peer-urls":["https://199.19.213.159:2380"]} {"level":"info","ts":"2026-05-27T13:51:30.191301Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T13:51:30.191435Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.159:2380"} {"level":"info","ts":"2026-05-27T13:51:30.191499Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.159:2380"} {"level":"info","ts":"2026-05-27T13:51:30.19174Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"63cd833aa17a09c7","initial-advertise-peer-urls":["https://199.19.213.159:2380"],"listen-peer-urls":["https://199.19.213.159:2380"],"advertise-client-urls":["https://199.19.213.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T13:51:30.191792Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T13:51:31.170014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T13:51:31.170084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T13:51:31.170125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 received MsgPreVoteResp from 63cd833aa17a09c7 at term 1"} {"level":"info","ts":"2026-05-27T13:51:31.170143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became candidate at term 2"} {"level":"info","ts":"2026-05-27T13:51:31.170154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 received MsgVoteResp from 63cd833aa17a09c7 at term 2"} {"level":"info","ts":"2026-05-27T13:51:31.170167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became leader at term 2"} {"level":"info","ts":"2026-05-27T13:51:31.170184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 63cd833aa17a09c7 elected leader 63cd833aa17a09c7 at term 2"} {"level":"info","ts":"2026-05-27T13:51:31.172717Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:51:31.174123Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27437233b92849be","local-member-id":"63cd833aa17a09c7","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:51:31.174294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T13:51:31.174295Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:51:31.174355Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T13:51:31.174335Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T13:51:31.174875Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T13:51:31.174913Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T13:51:31.176951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.159:2379"} {"level":"info","ts":"2026-05-27T13:51:31.174252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"63cd833aa17a09c7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.159:2379]}","request-path":"/0/members/63cd833aa17a09c7/attributes","cluster-id":"27437233b92849be","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T13:51:31.177965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-27T13:51:41.241202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.730584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-27T13:51:41.241291Z","caller":"traceutil/trace.go:171","msg":"trace[561553659] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:299; }","duration":"163.903929ms","start":"2026-05-27T13:51:41.077373Z","end":"2026-05-27T13:51:41.241277Z","steps":["trace[561553659] 'range keys from in-memory index tree' (duration: 163.603461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:41.241423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.22842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-27T13:51:41.241476Z","caller":"traceutil/trace.go:171","msg":"trace[2100732190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:299; }","duration":"113.326712ms","start":"2026-05-27T13:51:41.128136Z","end":"2026-05-27T13:51:41.241463Z","steps":["trace[2100732190] 'range keys from in-memory index tree' (duration: 113.081905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:41.778909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706043541945303,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T13:51:41.935603Z","caller":"traceutil/trace.go:171","msg":"trace[1097944180] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"687.846243ms","start":"2026-05-27T13:51:41.247705Z","end":"2026-05-27T13:51:41.935551Z","steps":["trace[1097944180] 'process raft request' (duration: 687.667419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:41.93661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.247662Z","time spent":"688.042148ms","remote":"127.0.0.1:37360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:51:42.441699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.244266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:51:42.441819Z","caller":"traceutil/trace.go:171","msg":"trace[1018370935] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:308; }","duration":"1.163893179s","start":"2026-05-27T13:51:41.277897Z","end":"2026-05-27T13:51:42.44179Z","steps":["trace[1018370935] 'read index received' (duration: 657.480671ms)","trace[1018370935] 'applied index is now lower than readState.Index' (duration: 506.410978ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:51:42.442009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164104474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-27T13:51:42.442076Z","caller":"traceutil/trace.go:171","msg":"trace[18657128] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"1.164189117s","start":"2026-05-27T13:51:41.277871Z","end":"2026-05-27T13:51:42.44206Z","steps":["trace[18657128] 'agreement among raft nodes before linearized reading' (duration: 1.164026523s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114056321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-27T13:51:42.442112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.277854Z","time spent":"1.164249338s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-05-27T13:51:42.442173Z","caller":"traceutil/trace.go:171","msg":"trace[1617932972] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:301; }","duration":"1.114193725s","start":"2026-05-27T13:51:41.327939Z","end":"2026-05-27T13:51:42.442133Z","steps":["trace[1617932972] 'agreement among raft nodes before linearized reading' (duration: 1.113982899s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.327923Z","time spent":"1.114289968s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:42.442254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.914172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-27T13:51:42.442162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.552188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-27T13:51:42.442365Z","caller":"traceutil/trace.go:171","msg":"trace[956414372] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:301; }","duration":"763.033365ms","start":"2026-05-27T13:51:41.679295Z","end":"2026-05-27T13:51:42.442329Z","steps":["trace[956414372] 'agreement among raft nodes before linearized reading' (duration: 762.864471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.206976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-05-27T13:51:42.442429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.679272Z","time spent":"763.143357ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-27T13:51:42.442447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014779557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-27T13:51:42.442532Z","caller":"traceutil/trace.go:171","msg":"trace[1762164037] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:301; }","duration":"1.014814757s","start":"2026-05-27T13:51:41.427656Z","end":"2026-05-27T13:51:42.442471Z","steps":["trace[1762164037] 'agreement among raft nodes before linearized reading' (duration: 1.014727545s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.660007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-27T13:51:42.442644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.427617Z","time spent":"1.015015423s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:42.44264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.262346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-27T13:51:42.442733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.564965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-27T13:51:42.442737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065495397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-27T13:51:42.442727Z","caller":"traceutil/trace.go:171","msg":"trace[144984871] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"915.348218ms","start":"2026-05-27T13:51:41.527337Z","end":"2026-05-27T13:51:42.442685Z","steps":["trace[144984871] 'agreement among raft nodes before linearized reading' (duration: 915.256496ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:42.442654Z","caller":"traceutil/trace.go:171","msg":"trace[1047074057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:301; }","duration":"512.76514ms","start":"2026-05-27T13:51:41.929878Z","end":"2026-05-27T13:51:42.442643Z","steps":["trace[1047074057] 'agreement among raft nodes before linearized reading' (duration: 512.659257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.110993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-05-27T13:51:42.44283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.929858Z","time spent":"512.959475ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T13:51:42.44281Z","caller":"traceutil/trace.go:171","msg":"trace[1574681826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"1.065582019s","start":"2026-05-27T13:51:41.377193Z","end":"2026-05-27T13:51:42.442775Z","steps":["trace[1574681826] 'agreement among raft nodes before linearized reading' (duration: 1.065482987s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.52732Z","time spent":"915.488542ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-27T13:51:42.44279Z","caller":"traceutil/trace.go:171","msg":"trace[1559869995] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"814.654997ms","start":"2026-05-27T13:51:41.62811Z","end":"2026-05-27T13:51:42.442765Z","steps":["trace[1559869995] 'agreement among raft nodes before linearized reading' (duration: 814.510643ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:42.442879Z","caller":"traceutil/trace.go:171","msg":"trace[300291900] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:301; }","duration":"847.256236ms","start":"2026-05-27T13:51:41.595596Z","end":"2026-05-27T13:51:42.442852Z","steps":["trace[300291900] 'agreement among raft nodes before linearized reading' (duration: 847.042631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.442886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.377177Z","time spent":"1.065696562s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:42.442908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.628088Z","time spent":"814.807921ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:42.442923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.59559Z","time spent":"847.324609ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-27T13:51:42.442568Z","caller":"traceutil/trace.go:171","msg":"trace[1646168938] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:301; }","duration":"965.462893ms","start":"2026-05-27T13:51:41.476987Z","end":"2026-05-27T13:51:42.44245Z","steps":["trace[1646168938] 'agreement among raft nodes before linearized reading' (duration: 965.053953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.443023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.476964Z","time spent":"966.046717ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:42.442561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.486601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T13:51:42.442527Z","caller":"traceutil/trace.go:171","msg":"trace[1860113344] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:301; }","duration":"846.910358ms","start":"2026-05-27T13:51:41.595514Z","end":"2026-05-27T13:51:42.442424Z","steps":["trace[1860113344] 'agreement among raft nodes before linearized reading' (duration: 846.521518ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:42.443081Z","caller":"traceutil/trace.go:171","msg":"trace[679402222] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"866.060905ms","start":"2026-05-27T13:51:41.577009Z","end":"2026-05-27T13:51:42.44307Z","steps":["trace[679402222] 'agreement among raft nodes before linearized reading' (duration: 865.450649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:42.443115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.576992Z","time spent":"866.114837ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:42.443124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.595496Z","time spent":"847.613616ms","remote":"127.0.0.1:37648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-27T13:51:42.441847Z","caller":"traceutil/trace.go:171","msg":"trace[217557049] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"1.192520147s","start":"2026-05-27T13:51:41.2493Z","end":"2026-05-27T13:51:42.441821Z","steps":["trace[217557049] 'process raft request' (duration: 806.911371ms)","trace[217557049] 'compare' (duration: 384.226051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:51:42.443545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:41.249292Z","time spent":"1.194149478s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:51:43.367297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.814355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:51:43.367398Z","caller":"traceutil/trace.go:171","msg":"trace[1365888233] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"920.800766ms","start":"2026-05-27T13:51:42.446578Z","end":"2026-05-27T13:51:43.367379Z","steps":["trace[1365888233] 'process raft request' (duration: 443.839197ms)","trace[1365888233] 'compare' (duration: 476.468917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:51:43.367459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.446567Z","time spent":"920.861198ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:51:43.589492Z","caller":"traceutil/trace.go:171","msg":"trace[1152123818] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:310; }","duration":"1.142482174s","start":"2026-05-27T13:51:42.446982Z","end":"2026-05-27T13:51:43.589464Z","steps":["trace[1152123818] 'read index received' (duration: 443.348205ms)","trace[1152123818] 'applied index is now lower than readState.Index' (duration: 699.132549ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:51:43.589587Z","caller":"traceutil/trace.go:171","msg":"trace[441036892] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"1.142792862s","start":"2026-05-27T13:51:42.446782Z","end":"2026-05-27T13:51:43.589575Z","steps":["trace[441036892] 'process raft request' (duration: 1.141033757s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:43.589613Z","caller":"traceutil/trace.go:171","msg":"trace[1111131350] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"1.138985425s","start":"2026-05-27T13:51:42.45061Z","end":"2026-05-27T13:51:43.589595Z","steps":["trace[1111131350] 'process raft request' (duration: 1.138696578s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.589725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.446775Z","time spent":"1.142890654s","remote":"127.0.0.1:37506","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-05-27T13:51:43.589738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.4506Z","time spent":"1.139067527s","remote":"127.0.0.1:37740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:51:43.589904Z","caller":"traceutil/trace.go:171","msg":"trace[1751403487] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"1.139413136s","start":"2026-05-27T13:51:42.450484Z","end":"2026-05-27T13:51:43.589897Z","steps":["trace[1751403487] 'process raft request' (duration: 1.138762549s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.589936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.450461Z","time spent":"1.139458546s","remote":"127.0.0.1:37360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:51:43.59004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143033698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-27T13:51:43.590095Z","caller":"traceutil/trace.go:171","msg":"trace[2052883187] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"1.1431274s","start":"2026-05-27T13:51:42.446954Z","end":"2026-05-27T13:51:43.590081Z","steps":["trace[2052883187] 'agreement among raft nodes before linearized reading' (duration: 1.142982246s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:43.590119Z","caller":"traceutil/trace.go:171","msg":"trace[294500358] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"835.517027ms","start":"2026-05-27T13:51:42.754595Z","end":"2026-05-27T13:51:43.590112Z","steps":["trace[294500358] 'process raft request' (duration: 834.800179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.590131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.446941Z","time spent":"1.143184512s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.590169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.754537Z","time spent":"835.600059ms","remote":"127.0.0.1:37506","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-05-27T13:51:43.592633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145202493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-05-27T13:51:43.592698Z","caller":"traceutil/trace.go:171","msg":"trace[388533978] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:307; }","duration":"1.145296725s","start":"2026-05-27T13:51:42.44739Z","end":"2026-05-27T13:51:43.592686Z","steps":["trace[388533978] 'agreement among raft nodes before linearized reading' (duration: 1.145148471s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.592769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.740131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-27T13:51:43.592727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.447381Z","time spent":"1.145340157s","remote":"127.0.0.1:37428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-27T13:51:43.592799Z","caller":"traceutil/trace.go:171","msg":"trace[520519291] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:307; }","duration":"481.799742ms","start":"2026-05-27T13:51:43.110992Z","end":"2026-05-27T13:51:43.592792Z","steps":["trace[520519291] 'agreement among raft nodes before linearized reading' (duration: 481.74ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.592818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:43.110976Z","time spent":"481.837383ms","remote":"127.0.0.1:37438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-27T13:51:43.59289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.926386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T13:51:43.592982Z","caller":"traceutil/trace.go:171","msg":"trace[1834423642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:307; }","duration":"633.0975ms","start":"2026-05-27T13:51:42.959868Z","end":"2026-05-27T13:51:43.592965Z","steps":["trace[1834423642] 'agreement among raft nodes before linearized reading' (duration: 632.888035ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:43.593001Z","caller":"traceutil/trace.go:171","msg":"trace[793797818] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"221.75601ms","start":"2026-05-27T13:51:43.371237Z","end":"2026-05-27T13:51:43.592993Z","steps":["trace[793797818] 'process raft request' (duration: 221.181295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.593099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143850368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-27T13:51:43.593128Z","caller":"traceutil/trace.go:171","msg":"trace[1110950144] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:307; }","duration":"1.14390903s","start":"2026-05-27T13:51:42.449211Z","end":"2026-05-27T13:51:43.59312Z","steps":["trace[1110950144] 'agreement among raft nodes before linearized reading' (duration: 1.143777137s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.593149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.449198Z","time spent":"1.143944631s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.593278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145612624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-27T13:51:43.593243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.959823Z","time spent":"633.191532ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T13:51:43.593305Z","caller":"traceutil/trace.go:171","msg":"trace[878286963] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:307; }","duration":"1.145647585s","start":"2026-05-27T13:51:42.44765Z","end":"2026-05-27T13:51:43.593298Z","steps":["trace[878286963] 'agreement among raft nodes before linearized reading' (duration: 1.145599473s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.593327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.447644Z","time spent":"1.145675055s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.593411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145764647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-27T13:51:43.593438Z","caller":"traceutil/trace.go:171","msg":"trace[2014468348] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:307; }","duration":"1.145801328s","start":"2026-05-27T13:51:42.447631Z","end":"2026-05-27T13:51:43.593432Z","steps":["trace[2014468348] 'agreement among raft nodes before linearized reading' (duration: 1.145700335s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.593455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.447626Z","time spent":"1.145824558s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.593535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145527621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-27T13:51:43.593549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145933872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-27T13:51:43.593557Z","caller":"traceutil/trace.go:171","msg":"trace[1433031821] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:307; }","duration":"1.145560002s","start":"2026-05-27T13:51:42.447991Z","end":"2026-05-27T13:51:43.593551Z","steps":["trace[1433031821] 'agreement among raft nodes before linearized reading' (duration: 1.145435299s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:43.593571Z","caller":"traceutil/trace.go:171","msg":"trace[964577211] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"1.145990323s","start":"2026-05-27T13:51:42.447572Z","end":"2026-05-27T13:51:43.593562Z","steps":["trace[964577211] 'agreement among raft nodes before linearized reading' (duration: 1.145953322s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.593595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.447565Z","time spent":"1.146024764s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.593575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.447986Z","time spent":"1.145584662s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.593415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144577847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-27T13:51:43.594732Z","caller":"traceutil/trace.go:171","msg":"trace[1566291601] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:307; }","duration":"1.145901901s","start":"2026-05-27T13:51:42.44882Z","end":"2026-05-27T13:51:43.594722Z","steps":["trace[1566291601] 'agreement among raft nodes before linearized reading' (duration: 1.144574027s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.594816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.448802Z","time spent":"1.145952973s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-27T13:51:43.594455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.785562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:51:43.594919Z","caller":"traceutil/trace.go:171","msg":"trace[806694737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:307; }","duration":"795.302664ms","start":"2026-05-27T13:51:42.799609Z","end":"2026-05-27T13:51:43.594911Z","steps":["trace[806694737] 'agreement among raft nodes before linearized reading' (duration: 794.048283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.594941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.79958Z","time spent":"795.355136ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T13:51:43.592705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.352276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T13:51:43.596765Z","caller":"traceutil/trace.go:171","msg":"trace[1617681449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:307; }","duration":"834.43968ms","start":"2026-05-27T13:51:42.762299Z","end":"2026-05-27T13:51:43.596739Z","steps":["trace[1617681449] 'agreement among raft nodes before linearized reading' (duration: 830.318585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:51:43.596853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:51:42.762279Z","time spent":"834.540823ms","remote":"127.0.0.1:37506","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-05-27T13:51:51.781882Z","caller":"traceutil/trace.go:171","msg":"trace[1350253198] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"101.394559ms","start":"2026-05-27T13:51:51.680462Z","end":"2026-05-27T13:51:51.781856Z","steps":["trace[1350253198] 'process raft request' (duration: 101.343918ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:51.781965Z","caller":"traceutil/trace.go:171","msg":"trace[1111147977] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"101.984344ms","start":"2026-05-27T13:51:51.67995Z","end":"2026-05-27T13:51:51.781934Z","steps":["trace[1111147977] 'process raft request' (duration: 101.700017ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:51:53.93086Z","caller":"traceutil/trace.go:171","msg":"trace[1930731958] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"120.374711ms","start":"2026-05-27T13:51:53.810466Z","end":"2026-05-27T13:51:53.930841Z","steps":["trace[1930731958] 'process raft request' (duration: 120.258338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:10.543314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.526044ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:52:10.543604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.556247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:52:10.543468Z","caller":"traceutil/trace.go:171","msg":"trace[845239625] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:519; }","duration":"287.699028ms","start":"2026-05-27T13:52:10.255747Z","end":"2026-05-27T13:52:10.543446Z","steps":["trace[845239625] 'range keys from in-memory index tree' (duration: 287.504814ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:10.543788Z","caller":"traceutil/trace.go:171","msg":"trace[1528749960] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"478.730442ms","start":"2026-05-27T13:52:10.065043Z","end":"2026-05-27T13:52:10.543774Z","steps":["trace[1528749960] 'process raft request' (duration: 105.927228ms)","trace[1528749960] 'compare' (duration: 372.427294ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:52:10.543844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:10.065029Z","time spent":"478.792724ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:52:10.544074Z","caller":"traceutil/trace.go:171","msg":"trace[527356507] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"465.07632ms","start":"2026-05-27T13:52:10.078973Z","end":"2026-05-27T13:52:10.54405Z","steps":["trace[527356507] 'process raft request' (duration: 464.752351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:10.544896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:10.078951Z","time spent":"465.841738ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:52:10.544535Z","caller":"traceutil/trace.go:171","msg":"trace[435929462] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:539; }","duration":"268.695152ms","start":"2026-05-27T13:52:10.275814Z","end":"2026-05-27T13:52:10.54451Z","steps":["trace[435929462] 'read index received' (duration: 242.061354ms)","trace[435929462] 'applied index is now lower than readState.Index' (duration: 26.632488ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:52:10.544618Z","caller":"traceutil/trace.go:171","msg":"trace[1973092558] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"361.786908ms","start":"2026-05-27T13:52:10.1828Z","end":"2026-05-27T13:52:10.544587Z","steps":["trace[1973092558] 'process raft request' (duration: 361.583343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:10.545152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:10.182769Z","time spent":"362.332901ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:52:10.544695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.828385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:52:10.545434Z","caller":"traceutil/trace.go:171","msg":"trace[1934202113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"269.582814ms","start":"2026-05-27T13:52:10.275772Z","end":"2026-05-27T13:52:10.545355Z","steps":["trace[1934202113] 'agreement among raft nodes before linearized reading' (duration: 268.818965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:17.440221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706043541945896,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T13:52:17.63146Z","caller":"traceutil/trace.go:171","msg":"trace[755689113] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"914.130947ms","start":"2026-05-27T13:52:16.717303Z","end":"2026-05-27T13:52:17.631434Z","steps":["trace[755689113] 'process raft request' (duration: 912.620179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:17.631667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:16.717274Z","time spent":"914.26616ms","remote":"127.0.0.1:37350","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-05-27T13:52:19.273008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000133534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T13:52:19.2731Z","caller":"traceutil/trace.go:171","msg":"trace[138234789] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000291669s","start":"2026-05-27T13:52:17.272791Z","end":"2026-05-27T13:52:19.273083Z","steps":["trace[138234789] 'agreement among raft nodes before linearized reading' (duration: 2.000148935s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:19.273141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:17.27278Z","time spent":"2.00035241s","remote":"127.0.0.1:37224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T13:52:19.534183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10722728s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:52:19.53438Z","caller":"traceutil/trace.go:171","msg":"trace[1515566305] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.839019632s","start":"2026-05-27T13:52:17.69534Z","end":"2026-05-27T13:52:19.534359Z","steps":["trace[1515566305] 'process raft request' (duration: 1.83895162s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:19.534468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:17.695315Z","time spent":"1.839104614s","remote":"127.0.0.1:37584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T13:52:19.534801Z","caller":"traceutil/trace.go:171","msg":"trace[280623073] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"2.595488373s","start":"2026-05-27T13:52:16.939251Z","end":"2026-05-27T13:52:19.534739Z","steps":["trace[280623073] 'read index received' (duration: 689.86827ms)","trace[280623073] 'applied index is now lower than readState.Index' (duration: 1.905616813s)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:52:19.534831Z","caller":"traceutil/trace.go:171","msg":"trace[154986042] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"2.752453241s","start":"2026-05-27T13:52:16.782341Z","end":"2026-05-27T13:52:19.534794Z","steps":["trace[154986042] 'process raft request' (duration: 1.644525783s)","trace[154986042] 'compare' (duration: 1.107113618s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:52:19.534979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:16.782324Z","time spent":"2.752565833s","remote":"127.0.0.1:37506","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-05-27T13:52:19.535017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.595769769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-689vg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T13:52:19.535051Z","caller":"traceutil/trace.go:171","msg":"trace[586413645] range","detail":"{range_begin:/registry/pods/kube-system/cilium-689vg; range_end:; response_count:1; response_revision:586; }","duration":"2.595849491s","start":"2026-05-27T13:52:16.93919Z","end":"2026-05-27T13:52:19.53504Z","steps":["trace[586413645] 'agreement among raft nodes before linearized reading' (duration: 2.595698507s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:19.535082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:16.939167Z","time spent":"2.595907873s","remote":"127.0.0.1:37438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-689vg\" "} {"level":"warn","ts":"2026-05-27T13:52:20.688168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.108939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-27T13:52:20.688147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.720762ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:52:20.688287Z","caller":"traceutil/trace.go:171","msg":"trace[543965977] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"431.881966ms","start":"2026-05-27T13:52:20.256384Z","end":"2026-05-27T13:52:20.688266Z","steps":["trace[543965977] 'range keys from in-memory index tree' (duration: 431.695192ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:20.688557Z","caller":"traceutil/trace.go:171","msg":"trace[1134680759] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"1.153671957s","start":"2026-05-27T13:52:19.534863Z","end":"2026-05-27T13:52:20.688535Z","steps":["trace[1134680759] 'read index received' (duration: 368.397864ms)","trace[1134680759] 'applied index is now lower than readState.Index' (duration: 785.272783ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:52:20.689996Z","caller":"traceutil/trace.go:171","msg":"trace[1244742998] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.410114771s","start":"2026-05-27T13:52:19.278434Z","end":"2026-05-27T13:52:20.688549Z","steps":["trace[1244742998] 'process raft request' (duration: 1.409849195s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:19.27841Z","time spent":"1.41164261s","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T13:52:20.690153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64397832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T13:52:20.690196Z","caller":"traceutil/trace.go:171","msg":"trace[1843563113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"2.644074512s","start":"2026-05-27T13:52:18.046109Z","end":"2026-05-27T13:52:20.690183Z","steps":["trace[1843563113] 'agreement among raft nodes before linearized reading' (duration: 2.643905388s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.721950906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T13:52:20.690236Z","caller":"traceutil/trace.go:171","msg":"trace[560320642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"2.722053498s","start":"2026-05-27T13:52:17.968168Z","end":"2026-05-27T13:52:20.690222Z","steps":["trace[560320642] 'agreement among raft nodes before linearized reading' (duration: 2.721900584s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:20.690274Z","caller":"traceutil/trace.go:171","msg":"trace[2080184327] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.141976784s","start":"2026-05-27T13:52:19.548287Z","end":"2026-05-27T13:52:20.690264Z","steps":["trace[2080184327] 'process raft request' (duration: 1.140126018s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:18.046078Z","time spent":"2.644149774s","remote":"127.0.0.1:37506","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":"warn","ts":"2026-05-27T13:52:20.690365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:19.548265Z","time spent":"1.142042715s","remote":"127.0.0.1:37438","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-05-27T13:52:20.690371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144398955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:52:20.690374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.791652985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T13:52:20.690403Z","caller":"traceutil/trace.go:171","msg":"trace[1655003657] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:588; }","duration":"1.144466777s","start":"2026-05-27T13:52:19.545926Z","end":"2026-05-27T13:52:20.690393Z","steps":["trace[1655003657] 'agreement among raft nodes before linearized reading' (duration: 1.144412025s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405464655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:52:20.690428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:19.545907Z","time spent":"1.144515087s","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-27T13:52:20.690387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.835514126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T13:52:20.690478Z","caller":"traceutil/trace.go:171","msg":"trace[950917725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"1.405598278s","start":"2026-05-27T13:52:19.284851Z","end":"2026-05-27T13:52:20.690449Z","steps":["trace[950917725] 'agreement among raft nodes before linearized reading' (duration: 1.405451024s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:19.284837Z","time spent":"1.405682251s","remote":"127.0.0.1:37236","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-05-27T13:52:20.690281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:17.968132Z","time spent":"2.722139881s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T13:52:20.690478Z","caller":"traceutil/trace.go:171","msg":"trace[269994227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"2.835659279s","start":"2026-05-27T13:52:17.8548Z","end":"2026-05-27T13:52:20.690459Z","steps":["trace[269994227] 'agreement among raft nodes before linearized reading' (duration: 2.835395893s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:17.854777Z","time spent":"2.835909195s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T13:52:20.690422Z","caller":"traceutil/trace.go:171","msg":"trace[512699245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"2.791794729s","start":"2026-05-27T13:52:17.898611Z","end":"2026-05-27T13:52:20.690406Z","steps":["trace[512699245] 'agreement among raft nodes before linearized reading' (duration: 2.791586264s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:20.690772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:17.898575Z","time spent":"2.792180598s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T13:52:21.200858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706043541945906,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T13:52:21.470321Z","caller":"traceutil/trace.go:171","msg":"trace[1841754563] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"773.286823ms","start":"2026-05-27T13:52:20.697012Z","end":"2026-05-27T13:52:21.470299Z","steps":["trace[1841754563] 'process raft request' (duration: 728.408197ms)","trace[1841754563] 'compare' (duration: 44.616469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:52:21.470444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:20.696994Z","time spent":"773.373535ms","remote":"127.0.0.1:37506","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-05-27T13:52:21.470873Z","caller":"traceutil/trace.go:171","msg":"trace[479282733] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"773.050896ms","start":"2026-05-27T13:52:20.697806Z","end":"2026-05-27T13:52:21.470857Z","steps":["trace[479282733] 'process raft request' (duration: 772.986695ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:21.470887Z","caller":"traceutil/trace.go:171","msg":"trace[564656174] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"773.120509ms","start":"2026-05-27T13:52:20.697741Z","end":"2026-05-27T13:52:21.470862Z","steps":["trace[564656174] 'process raft request' (duration: 773.003716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:21.470969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:20.6978Z","time spent":"773.123709ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:52:21.471021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:20.69773Z","time spent":"773.221141ms","remote":"127.0.0.1:37506","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-05-27T13:52:21.471194Z","caller":"traceutil/trace.go:171","msg":"trace[1099068697] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"770.299738ms","start":"2026-05-27T13:52:20.70087Z","end":"2026-05-27T13:52:21.47117Z","steps":["trace[1099068697] 'process raft request' (duration: 769.95161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:21.471308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:20.700858Z","time spent":"770.38483ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:52:21.471363Z","caller":"traceutil/trace.go:171","msg":"trace[1420499474] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:610; }","duration":"771.57605ms","start":"2026-05-27T13:52:20.699776Z","end":"2026-05-27T13:52:21.471352Z","steps":["trace[1420499474] 'read index received' (duration: 725.73225ms)","trace[1420499474] 'applied index is now lower than readState.Index' (duration: 45.84304ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:52:21.471401Z","caller":"traceutil/trace.go:171","msg":"trace[1719566874] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"388.320564ms","start":"2026-05-27T13:52:21.083072Z","end":"2026-05-27T13:52:21.471392Z","steps":["trace[1719566874] 'process raft request' (duration: 387.920744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:21.471433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:21.083039Z","time spent":"388.378305ms","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:52:21.471449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.671842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:52:21.471478Z","caller":"traceutil/trace.go:171","msg":"trace[553339140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"771.722294ms","start":"2026-05-27T13:52:20.699748Z","end":"2026-05-27T13:52:21.47147Z","steps":["trace[553339140] 'agreement among raft nodes before linearized reading' (duration: 771.645692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:21.471501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:20.699731Z","time spent":"771.765155ms","remote":"127.0.0.1:37236","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-05-27T13:52:33.718782Z","caller":"traceutil/trace.go:171","msg":"trace[1973534192] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"173.439532ms","start":"2026-05-27T13:52:33.545315Z","end":"2026-05-27T13:52:33.718755Z","steps":["trace[1973534192] 'process raft request' (duration: 173.280548ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:33.733277Z","caller":"traceutil/trace.go:171","msg":"trace[96373228] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"186.89183ms","start":"2026-05-27T13:52:33.546371Z","end":"2026-05-27T13:52:33.733263Z","steps":["trace[96373228] 'process raft request' (duration: 186.653914ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:33.733277Z","caller":"traceutil/trace.go:171","msg":"trace[559511495] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"185.825883ms","start":"2026-05-27T13:52:33.547428Z","end":"2026-05-27T13:52:33.733254Z","steps":["trace[559511495] 'process raft request' (duration: 185.753011ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:33.733385Z","caller":"traceutil/trace.go:171","msg":"trace[1248717532] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"185.849383ms","start":"2026-05-27T13:52:33.547503Z","end":"2026-05-27T13:52:33.733352Z","steps":["trace[1248717532] 'process raft request' (duration: 185.71662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:37.174446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.232983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T13:52:37.174561Z","caller":"traceutil/trace.go:171","msg":"trace[455525599] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:758; }","duration":"736.376456ms","start":"2026-05-27T13:52:36.438131Z","end":"2026-05-27T13:52:37.174508Z","steps":["trace[455525599] 'count revisions from in-memory index tree' (duration: 736.1144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:37.174613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:36.438103Z","time spent":"736.496119ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-27T13:52:37.174657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.53086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T13:52:37.175382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131191283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-phk7q\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-27T13:52:37.175413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.670019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:52:37.175445Z","caller":"traceutil/trace.go:171","msg":"trace[871503794] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-phk7q; range_end:; response_count:1; response_revision:758; }","duration":"1.131847279s","start":"2026-05-27T13:52:36.043584Z","end":"2026-05-27T13:52:37.175431Z","steps":["trace[871503794] 'range keys from in-memory index tree' (duration: 1.131042459s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:37.175453Z","caller":"traceutil/trace.go:171","msg":"trace[1876080764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"898.814472ms","start":"2026-05-27T13:52:36.276628Z","end":"2026-05-27T13:52:37.175442Z","steps":["trace[1876080764] 'range keys from in-memory index tree' (duration: 898.568665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:52:37.175478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:36.043541Z","time spent":"1.131928421s","remote":"127.0.0.1:37438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-phk7q\" "} {"level":"warn","ts":"2026-05-27T13:52:37.175482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:52:36.27661Z","time spent":"898.865063ms","remote":"127.0.0.1:37236","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-05-27T13:52:37.175501Z","caller":"traceutil/trace.go:171","msg":"trace[165777949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"243.680884ms","start":"2026-05-27T13:52:36.931053Z","end":"2026-05-27T13:52:37.174734Z","steps":["trace[165777949] 'range keys from in-memory index tree' (duration: 243.294153ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:49.916199Z","caller":"traceutil/trace.go:171","msg":"trace[586470016] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"102.620655ms","start":"2026-05-27T13:52:49.813329Z","end":"2026-05-27T13:52:49.91595Z","steps":["trace[586470016] 'process raft request' (duration: 91.781703ms)","trace[586470016] 'compare' (duration: 10.710138ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:52:49.930317Z","caller":"traceutil/trace.go:171","msg":"trace[1986063340] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"115.210811ms","start":"2026-05-27T13:52:49.81508Z","end":"2026-05-27T13:52:49.930291Z","steps":["trace[1986063340] 'process raft request' (duration: 115.075117ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:52:53.230712Z","caller":"traceutil/trace.go:171","msg":"trace[1761931746] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"102.468601ms","start":"2026-05-27T13:52:53.128215Z","end":"2026-05-27T13:52:53.230683Z","steps":["trace[1761931746] 'process raft request' (duration: 102.287537ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:53:13.808424Z","caller":"traceutil/trace.go:171","msg":"trace[985091192] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"339.13518ms","start":"2026-05-27T13:53:13.469268Z","end":"2026-05-27T13:53:13.808403Z","steps":["trace[985091192] 'process raft request' (duration: 338.994556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:13.808556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:13.469253Z","time spent":"339.233972ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:53:14.965523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.378507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:53:14.965596Z","caller":"traceutil/trace.go:171","msg":"trace[711310600] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:976; }","duration":"978.49722ms","start":"2026-05-27T13:53:13.98708Z","end":"2026-05-27T13:53:14.965577Z","steps":["trace[711310600] 'count revisions from in-memory index tree' (duration: 978.278065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.965626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:13.987057Z","time spent":"978.561933ms","remote":"127.0.0.1:37548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-27T13:53:14.965644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.185538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T13:53:14.965709Z","caller":"traceutil/trace.go:171","msg":"trace[1747195599] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:976; }","duration":"318.304991ms","start":"2026-05-27T13:53:14.647388Z","end":"2026-05-27T13:53:14.965693Z","steps":["trace[1747195599] 'count revisions from in-memory index tree' (duration: 318.057595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.965744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:14.647367Z","time spent":"318.366842ms","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-27T13:53:14.965824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.494771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T13:53:14.965847Z","caller":"traceutil/trace.go:171","msg":"trace[1126652009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"976.552022ms","start":"2026-05-27T13:53:13.989287Z","end":"2026-05-27T13:53:14.965839Z","steps":["trace[1126652009] 'range keys from in-memory index tree' (duration: 976.364558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.965864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:13.989268Z","time spent":"976.592164ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:53:14.965922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.651603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T13:53:14.965985Z","caller":"traceutil/trace.go:171","msg":"trace[587781167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"818.772256ms","start":"2026-05-27T13:53:14.147196Z","end":"2026-05-27T13:53:14.965968Z","steps":["trace[587781167] 'range keys from in-memory index tree' (duration: 818.453298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.966013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.57995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:53:14.966033Z","caller":"traceutil/trace.go:171","msg":"trace[977610322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"689.637962ms","start":"2026-05-27T13:53:14.27639Z","end":"2026-05-27T13:53:14.966028Z","steps":["trace[977610322] 'range keys from in-memory index tree' (duration: 689.508399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.966038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:14.147171Z","time spent":"818.848638ms","remote":"127.0.0.1:37506","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-05-27T13:53:14.966051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:14.276369Z","time spent":"689.678123ms","remote":"127.0.0.1:37236","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-05-27T13:53:14.966333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.889496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T13:53:14.966384Z","caller":"traceutil/trace.go:171","msg":"trace[1344313511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"932.942287ms","start":"2026-05-27T13:53:14.033431Z","end":"2026-05-27T13:53:14.966374Z","steps":["trace[1344313511] 'range keys from in-memory index tree' (duration: 932.729782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.966414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:14.033397Z","time spent":"933.009019ms","remote":"127.0.0.1:37506","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":"warn","ts":"2026-05-27T13:53:14.966564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.501818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T13:53:14.966599Z","caller":"traceutil/trace.go:171","msg":"trace[1526000481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"826.611801ms","start":"2026-05-27T13:53:14.139977Z","end":"2026-05-27T13:53:14.966589Z","steps":["trace[1526000481] 'range keys from in-memory index tree' (duration: 826.261672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.966626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:14.13994Z","time spent":"826.678402ms","remote":"127.0.0.1:37506","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-05-27T13:53:14.96664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.412661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T13:53:14.966665Z","caller":"traceutil/trace.go:171","msg":"trace[1323638161] range","detail":"{range_begin:/registry/masterleases/199.19.213.159; range_end:; response_count:1; response_revision:976; }","duration":"872.481272ms","start":"2026-05-27T13:53:14.094176Z","end":"2026-05-27T13:53:14.966658Z","steps":["trace[1323638161] 'range keys from in-memory index tree' (duration: 872.271658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:14.966685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:14.094151Z","time spent":"872.529454ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.159\" "} {"level":"info","ts":"2026-05-27T13:53:15.001238Z","caller":"traceutil/trace.go:171","msg":"trace[2098196921] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"126.410116ms","start":"2026-05-27T13:53:14.874788Z","end":"2026-05-27T13:53:15.001198Z","steps":["trace[2098196921] 'process raft request' (duration: 126.086928ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:53:39.543902Z","caller":"traceutil/trace.go:171","msg":"trace[889657709] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"421.635673ms","start":"2026-05-27T13:53:39.122233Z","end":"2026-05-27T13:53:39.543868Z","steps":["trace[889657709] 'read index received' (duration: 421.475719ms)","trace[889657709] 'applied index is now lower than readState.Index' (duration: 158.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:53:39.543983Z","caller":"traceutil/trace.go:171","msg":"trace[670338305] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"431.878297ms","start":"2026-05-27T13:53:39.112076Z","end":"2026-05-27T13:53:39.543954Z","steps":["trace[670338305] 'process raft request' (duration: 431.60271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:39.544171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.9257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T13:53:39.544436Z","caller":"traceutil/trace.go:171","msg":"trace[1721109812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"422.220587ms","start":"2026-05-27T13:53:39.122186Z","end":"2026-05-27T13:53:39.544407Z","steps":["trace[1721109812] 'agreement among raft nodes before linearized reading' (duration: 421.850918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:39.544525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:39.12217Z","time spent":"422.3253ms","remote":"127.0.0.1:37506","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-05-27T13:53:39.544347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:39.112056Z","time spent":"432.180265ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:53:39.667616Z","caller":"traceutil/trace.go:171","msg":"trace[2038420341] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"123.498644ms","start":"2026-05-27T13:53:39.544084Z","end":"2026-05-27T13:53:39.667582Z","steps":["trace[2038420341] 'read index received' (duration: 32.93226ms)","trace[2038420341] 'applied index is now lower than readState.Index' (duration: 90.561714ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:53:39.667641Z","caller":"traceutil/trace.go:171","msg":"trace[2120103145] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"118.732815ms","start":"2026-05-27T13:53:39.54888Z","end":"2026-05-27T13:53:39.667613Z","steps":["trace[2120103145] 'process raft request' (duration: 118.589071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:39.667804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.56598ms","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-05-27T13:53:39.667774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.246131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T13:53:39.66792Z","caller":"traceutil/trace.go:171","msg":"trace[730473909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"523.688372ms","start":"2026-05-27T13:53:39.144195Z","end":"2026-05-27T13:53:39.667883Z","steps":["trace[730473909] 'agreement among raft nodes before linearized reading' (duration: 523.400775ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:53:39.667952Z","caller":"traceutil/trace.go:171","msg":"trace[89840720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1058; }","duration":"531.471827ms","start":"2026-05-27T13:53:39.136465Z","end":"2026-05-27T13:53:39.667937Z","steps":["trace[89840720] 'agreement among raft nodes before linearized reading' (duration: 531.172799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:39.668014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:39.144184Z","time spent":"523.820075ms","remote":"127.0.0.1:37506","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-05-27T13:53:39.668034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:39.136446Z","time spent":"531.576159ms","remote":"127.0.0.1:37506","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":"warn","ts":"2026-05-27T13:53:39.667856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.871153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:53:39.668199Z","caller":"traceutil/trace.go:171","msg":"trace[2065918817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"394.263552ms","start":"2026-05-27T13:53:39.273911Z","end":"2026-05-27T13:53:39.668174Z","steps":["trace[2065918817] 'agreement among raft nodes before linearized reading' (duration: 393.827771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:39.668249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:39.273892Z","time spent":"394.348634ms","remote":"127.0.0.1:37236","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-05-27T13:53:39.667839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.345833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T13:53:39.668422Z","caller":"traceutil/trace.go:171","msg":"trace[1831630827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"535.99347ms","start":"2026-05-27T13:53:39.13242Z","end":"2026-05-27T13:53:39.668413Z","steps":["trace[1831630827] 'agreement among raft nodes before linearized reading' (duration: 535.266391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:39.668463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:39.132401Z","time spent":"536.053081ms","remote":"127.0.0.1:37506","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":"info","ts":"2026-05-27T13:53:56.913211Z","caller":"traceutil/trace.go:171","msg":"trace[308868375] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"684.433433ms","start":"2026-05-27T13:53:56.228762Z","end":"2026-05-27T13:53:56.913196Z","steps":["trace[308868375] 'process raft request' (duration: 684.34459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:56.913315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:56.228728Z","time spent":"684.529115ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:53:58.576002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706043541947183,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:53:58.86272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.948917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:53:58.862885Z","caller":"traceutil/trace.go:171","msg":"trace[716831837] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1219; }","duration":"787.835466ms","start":"2026-05-27T13:53:58.075031Z","end":"2026-05-27T13:53:58.862866Z","steps":["trace[716831837] 'read index received' (duration: 779.55367ms)","trace[716831837] 'applied index is now lower than readState.Index' (duration: 8.280966ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:53:58.862931Z","caller":"traceutil/trace.go:171","msg":"trace[525106302] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"925.42809ms","start":"2026-05-27T13:53:57.937482Z","end":"2026-05-27T13:53:58.862911Z","steps":["trace[525106302] 'process raft request' (duration: 925.335708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:58.863014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:57.937467Z","time spent":"925.506432ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:53:58.863199Z","caller":"traceutil/trace.go:171","msg":"trace[321391612] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"925.664356ms","start":"2026-05-27T13:53:57.937482Z","end":"2026-05-27T13:53:58.863147Z","steps":["trace[321391612] 'process raft request' (duration: 125.158195ms)","trace[321391612] 'compare' (duration: 799.843875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:53:58.863331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.732367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:53:58.863372Z","caller":"traceutil/trace.go:171","msg":"trace[147195028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"585.805589ms","start":"2026-05-27T13:53:58.277553Z","end":"2026-05-27T13:53:58.863358Z","steps":["trace[147195028] 'agreement among raft nodes before linearized reading' (duration: 585.745877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:58.863406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:58.277531Z","time spent":"585.864619ms","remote":"127.0.0.1:37236","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-05-27T13:53:58.863415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:57.937466Z","time spent":"925.8361ms","remote":"127.0.0.1:37506","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-05-27T13:53:58.863609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.883685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T13:53:58.863648Z","caller":"traceutil/trace.go:171","msg":"trace[1096793379] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1172; }","duration":"144.952477ms","start":"2026-05-27T13:53:58.718684Z","end":"2026-05-27T13:53:58.863637Z","steps":["trace[1096793379] 'agreement among raft nodes before linearized reading' (duration: 144.835355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:58.863737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.706028ms","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-05-27T13:53:58.863781Z","caller":"traceutil/trace.go:171","msg":"trace[539530903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"788.752019ms","start":"2026-05-27T13:53:58.075016Z","end":"2026-05-27T13:53:58.863768Z","steps":["trace[539530903] 'agreement among raft nodes before linearized reading' (duration: 787.936058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:53:58.863812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:58.07495Z","time spent":"788.854752ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T13:53:59.352244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.801257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:53:59.352591Z","caller":"traceutil/trace.go:171","msg":"trace[891738620] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"484.839194ms","start":"2026-05-27T13:53:58.867701Z","end":"2026-05-27T13:53:59.35254Z","steps":["trace[891738620] 'process raft request' (duration: 379.599887ms)","trace[891738620] 'compare' (duration: 104.292044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:53:59.352824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:53:58.867681Z","time spent":"485.010169ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:54:14.074237Z","caller":"traceutil/trace.go:171","msg":"trace[646946186] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"239.798848ms","start":"2026-05-27T13:54:13.834418Z","end":"2026-05-27T13:54:14.074217Z","steps":["trace[646946186] 'process raft request' (duration: 206.716794ms)","trace[646946186] 'compare' (duration: 32.581491ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:54:14.597703Z","caller":"traceutil/trace.go:171","msg":"trace[1046467098] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"518.724429ms","start":"2026-05-27T13:54:14.078949Z","end":"2026-05-27T13:54:14.597674Z","steps":["trace[1046467098] 'process raft request' (duration: 518.417921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:14.597851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:14.078936Z","time spent":"518.836612ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:54:14.614711Z","caller":"traceutil/trace.go:171","msg":"trace[2051418296] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"516.655887ms","start":"2026-05-27T13:54:14.098038Z","end":"2026-05-27T13:54:14.614694Z","steps":["trace[2051418296] 'read index received' (duration: 499.626903ms)","trace[2051418296] 'applied index is now lower than readState.Index' (duration: 17.028154ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:54:14.614788Z","caller":"traceutil/trace.go:171","msg":"trace[1589274836] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"534.589603ms","start":"2026-05-27T13:54:14.080193Z","end":"2026-05-27T13:54:14.614782Z","steps":["trace[1589274836] 'process raft request' (duration: 534.397959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:14.61486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:14.08017Z","time spent":"534.634085ms","remote":"127.0.0.1:37506","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-05-27T13:54:14.614996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.959706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.159\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T13:54:14.615017Z","caller":"traceutil/trace.go:171","msg":"trace[1131000069] range","detail":"{range_begin:/registry/masterleases/199.19.213.159; range_end:; response_count:1; response_revision:1222; }","duration":"517.021577ms","start":"2026-05-27T13:54:14.097991Z","end":"2026-05-27T13:54:14.615012Z","steps":["trace[1131000069] 'agreement among raft nodes before linearized reading' (duration: 516.948635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:14.615032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:14.09797Z","time spent":"517.059507ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.159\" "} {"level":"warn","ts":"2026-05-27T13:54:14.615203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.93621ms","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-05-27T13:54:14.615222Z","caller":"traceutil/trace.go:171","msg":"trace[163493758] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1222; }","duration":"515.97592ms","start":"2026-05-27T13:54:14.099241Z","end":"2026-05-27T13:54:14.615217Z","steps":["trace[163493758] 'agreement among raft nodes before linearized reading' (duration: 515.928119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:14.615235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:14.09923Z","time spent":"516.001791ms","remote":"127.0.0.1:37614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-27T13:54:14.615404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.615876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:54:14.615495Z","caller":"traceutil/trace.go:171","msg":"trace[1919825909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"338.77645ms","start":"2026-05-27T13:54:14.276696Z","end":"2026-05-27T13:54:14.615473Z","steps":["trace[1919825909] 'agreement among raft nodes before linearized reading' (duration: 338.600306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:14.61555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:14.276653Z","time spent":"338.885034ms","remote":"127.0.0.1:37236","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-05-27T13:54:15.206359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.618669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:54:15.20646Z","caller":"traceutil/trace.go:171","msg":"trace[2118017832] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"161.363316ms","start":"2026-05-27T13:54:15.045079Z","end":"2026-05-27T13:54:15.206442Z","steps":["trace[2118017832] 'process raft request' (duration: 14.592013ms)","trace[2118017832] 'compare' (duration: 146.452425ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:54:15.758138Z","caller":"traceutil/trace.go:171","msg":"trace[2058884638] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"228.900563ms","start":"2026-05-27T13:54:15.529211Z","end":"2026-05-27T13:54:15.758112Z","steps":["trace[2058884638] 'process raft request' (duration: 228.752319ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:16.3716Z","caller":"traceutil/trace.go:171","msg":"trace[480429883] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"291.625127ms","start":"2026-05-27T13:54:16.079949Z","end":"2026-05-27T13:54:16.371574Z","steps":["trace[480429883] 'process raft request' (duration: 291.018832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:17.266943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.107155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:54:17.267033Z","caller":"traceutil/trace.go:171","msg":"trace[1606458022] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1230; }","duration":"285.273079ms","start":"2026-05-27T13:54:16.98174Z","end":"2026-05-27T13:54:17.267013Z","steps":["trace[1606458022] 'count revisions from in-memory index tree' (duration: 284.947921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:17.266957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.472737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T13:54:17.267188Z","caller":"traceutil/trace.go:171","msg":"trace[1113347952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"223.730274ms","start":"2026-05-27T13:54:17.043434Z","end":"2026-05-27T13:54:17.267164Z","steps":["trace[1113347952] 'range keys from in-memory index tree' (duration: 223.255852ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:17.525437Z","caller":"traceutil/trace.go:171","msg":"trace[885520993] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"253.564914ms","start":"2026-05-27T13:54:17.271854Z","end":"2026-05-27T13:54:17.525419Z","steps":["trace[885520993] 'process raft request' (duration: 253.460142ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:17.814404Z","caller":"traceutil/trace.go:171","msg":"trace[1504538766] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"538.371422ms","start":"2026-05-27T13:54:17.276005Z","end":"2026-05-27T13:54:17.814377Z","steps":["trace[1504538766] 'read index received' (duration: 249.465663ms)","trace[1504538766] 'applied index is now lower than readState.Index' (duration: 288.904399ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:54:17.814471Z","caller":"traceutil/trace.go:171","msg":"trace[1626964844] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"542.569007ms","start":"2026-05-27T13:54:17.27187Z","end":"2026-05-27T13:54:17.814439Z","steps":["trace[1626964844] 'process raft request' (duration: 542.256329ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:17.814515Z","caller":"traceutil/trace.go:171","msg":"trace[160139646] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"315.547ms","start":"2026-05-27T13:54:17.498941Z","end":"2026-05-27T13:54:17.814488Z","steps":["trace[160139646] 'process raft request' (duration: 315.375066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:17.814608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:17.271845Z","time spent":"542.690549ms","remote":"127.0.0.1:37506","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-05-27T13:54:17.814641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.638443ms","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":"warn","ts":"2026-05-27T13:54:17.814625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.595258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:54:17.814691Z","caller":"traceutil/trace.go:171","msg":"trace[915827011] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1233; }","duration":"495.744956ms","start":"2026-05-27T13:54:17.31893Z","end":"2026-05-27T13:54:17.814675Z","steps":["trace[915827011] 'agreement among raft nodes before linearized reading' (duration: 495.646963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:17.814685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:17.498922Z","time spent":"315.654223ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:54:17.814724Z","caller":"traceutil/trace.go:171","msg":"trace[1648963048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"538.737381ms","start":"2026-05-27T13:54:17.275964Z","end":"2026-05-27T13:54:17.814702Z","steps":["trace[1648963048] 'agreement among raft nodes before linearized reading' (duration: 538.570897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:17.814738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:17.318905Z","time spent":"495.818578ms","remote":"127.0.0.1:37740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-27T13:54:17.814778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:17.27595Z","time spent":"538.818743ms","remote":"127.0.0.1:37236","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-05-27T13:54:22.51617Z","caller":"traceutil/trace.go:171","msg":"trace[523844730] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"101.480115ms","start":"2026-05-27T13:54:22.414665Z","end":"2026-05-27T13:54:22.516145Z","steps":["trace[523844730] 'process raft request' (duration: 101.334052ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:27.8663Z","caller":"traceutil/trace.go:171","msg":"trace[1965785473] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"301.128353ms","start":"2026-05-27T13:54:27.565141Z","end":"2026-05-27T13:54:27.866269Z","steps":["trace[1965785473] 'process raft request' (duration: 300.932578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:27.866494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:27.565119Z","time spent":"301.285157ms","remote":"127.0.0.1:37506","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-05-27T13:54:28.409237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706043541947519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T13:54:30.201579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501100142s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T13:54:30.201676Z","caller":"traceutil/trace.go:171","msg":"trace[1312813698] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"2.294148025s","start":"2026-05-27T13:54:27.907512Z","end":"2026-05-27T13:54:30.20166Z","steps":["trace[1312813698] 'process raft request' (duration: 792.897379ms)","trace[1312813698] 'compare' (duration: 1.500807225s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:54:30.201729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:27.907488Z","time spent":"2.294212537s","remote":"127.0.0.1:37506","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-05-27T13:54:30.465806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.551804078s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T13:54:30.468954Z","caller":"traceutil/trace.go:171","msg":"trace[1428540929] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"1.90455836s","start":"2026-05-27T13:54:28.564377Z","end":"2026-05-27T13:54:30.468935Z","steps":["trace[1428540929] 'process raft request' (duration: 1.904349295s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:30.468944Z","caller":"traceutil/trace.go:171","msg":"trace[1091711953] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"2.319656798s","start":"2026-05-27T13:54:28.149259Z","end":"2026-05-27T13:54:30.468916Z","steps":["trace[1091711953] 'process raft request' (duration: 2.316816378s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.46914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.564352Z","time spent":"1.904668903s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T13:54:30.469208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.149237Z","time spent":"2.319879493s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T13:54:30.468839Z","caller":"traceutil/trace.go:171","msg":"trace[465329782] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1381; }","duration":"2.560247419s","start":"2026-05-27T13:54:27.908556Z","end":"2026-05-27T13:54:30.468803Z","steps":["trace[465329782] 'read index received' (duration: 791.885454ms)","trace[465329782] 'applied index is now lower than readState.Index' (duration: 1.768360525s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T13:54:30.469551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.560913356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T13:54:30.469728Z","caller":"traceutil/trace.go:171","msg":"trace[1406178533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"2.561178522s","start":"2026-05-27T13:54:27.908523Z","end":"2026-05-27T13:54:30.469701Z","steps":["trace[1406178533] 'agreement among raft nodes before linearized reading' (duration: 2.560568257s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.469819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:27.90851Z","time spent":"2.561295975s","remote":"127.0.0.1:37506","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-05-27T13:54:30.496286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.351480316s","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:533"} {"level":"warn","ts":"2026-05-27T13:54:30.496355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718174881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T13:54:30.496388Z","caller":"traceutil/trace.go:171","msg":"trace[100673623] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"2.35162651s","start":"2026-05-27T13:54:28.144738Z","end":"2026-05-27T13:54:30.496365Z","steps":["trace[100673623] 'agreement among raft nodes before linearized reading' (duration: 2.351299192s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:30.496428Z","caller":"traceutil/trace.go:171","msg":"trace[1490023619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"1.718286074s","start":"2026-05-27T13:54:28.778126Z","end":"2026-05-27T13:54:30.496412Z","steps":["trace[1490023619] 'agreement among raft nodes before linearized reading' (duration: 1.718035358s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.496435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.144723Z","time spent":"2.351702242s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-27T13:54:30.496472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.778053Z","time spent":"1.718412008s","remote":"127.0.0.1:37506","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-05-27T13:54:30.496482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742262979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T13:54:30.496549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.09324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-27T13:54:30.496492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844720207s","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":"info","ts":"2026-05-27T13:54:30.496587Z","caller":"traceutil/trace.go:171","msg":"trace[1008034615] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1330; }","duration":"1.742441202s","start":"2026-05-27T13:54:28.754122Z","end":"2026-05-27T13:54:30.496563Z","steps":["trace[1008034615] 'agreement among raft nodes before linearized reading' (duration: 1.742206076s)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:54:30.496621Z","caller":"traceutil/trace.go:171","msg":"trace[1732126350] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"978.192992ms","start":"2026-05-27T13:54:29.518405Z","end":"2026-05-27T13:54:30.496598Z","steps":["trace[1732126350] 'agreement among raft nodes before linearized reading' (duration: 977.992257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.496654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.754101Z","time spent":"1.742540155s","remote":"127.0.0.1:37420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-27T13:54:30.496697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.573743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-27T13:54:30.496705Z","caller":"traceutil/trace.go:171","msg":"trace[775768980] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1330; }","duration":"1.844919652s","start":"2026-05-27T13:54:28.651702Z","end":"2026-05-27T13:54:30.496622Z","steps":["trace[775768980] 'agreement among raft nodes before linearized reading' (duration: 1.844617525s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.496707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:29.518389Z","time spent":"978.307925ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T13:54:30.496738Z","caller":"traceutil/trace.go:171","msg":"trace[1180711820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1330; }","duration":"625.637635ms","start":"2026-05-27T13:54:29.871087Z","end":"2026-05-27T13:54:30.496725Z","steps":["trace[1180711820] 'agreement among raft nodes before linearized reading' (duration: 625.520872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.496783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:29.871068Z","time spent":"625.705917ms","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T13:54:30.49679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.651683Z","time spent":"1.845091986s","remote":"127.0.0.1:37506","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-05-27T13:54:30.496589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220488797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:54:30.496845Z","caller":"traceutil/trace.go:171","msg":"trace[651264081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"1.220737363s","start":"2026-05-27T13:54:29.276095Z","end":"2026-05-27T13:54:30.496832Z","steps":["trace[651264081] 'agreement among raft nodes before linearized reading' (duration: 1.220473816s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.49656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739833378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T13:54:30.496892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:29.276036Z","time spent":"1.220846046s","remote":"127.0.0.1:37236","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-05-27T13:54:30.496924Z","caller":"traceutil/trace.go:171","msg":"trace[1320656311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"1.740267159s","start":"2026-05-27T13:54:28.756645Z","end":"2026-05-27T13:54:30.496912Z","steps":["trace[1320656311] 'agreement among raft nodes before linearized reading' (duration: 1.739728465s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:54:30.496963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T13:54:28.75663Z","time spent":"1.7403212s","remote":"127.0.0.1:37506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T13:54:46.710928Z","caller":"traceutil/trace.go:171","msg":"trace[28267673] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"108.596991ms","start":"2026-05-27T13:54:46.602295Z","end":"2026-05-27T13:54:46.710892Z","steps":["trace[28267673] 'process raft request' (duration: 78.573947ms)","trace[28267673] 'compare' (duration: 29.844509ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:57:25.390537Z","caller":"traceutil/trace.go:171","msg":"trace[1568640194] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"164.118659ms","start":"2026-05-27T13:57:25.226393Z","end":"2026-05-27T13:57:25.390511Z","steps":["trace[1568640194] 'read index received' (duration: 164.009017ms)","trace[1568640194] 'applied index is now lower than readState.Index' (duration: 108.352µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T13:57:25.390684Z","caller":"traceutil/trace.go:171","msg":"trace[397190448] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"186.443642ms","start":"2026-05-27T13:57:25.204186Z","end":"2026-05-27T13:57:25.39063Z","steps":["trace[397190448] 'process raft request' (duration: 186.175886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:57:25.390744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.328345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T13:57:25.39092Z","caller":"traceutil/trace.go:171","msg":"trace[1173580572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2131; }","duration":"164.55158ms","start":"2026-05-27T13:57:25.226358Z","end":"2026-05-27T13:57:25.39091Z","steps":["trace[1173580572] 'agreement among raft nodes before linearized reading' (duration: 164.274673ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:57:25.437083Z","caller":"traceutil/trace.go:171","msg":"trace[118126271] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"125.812349ms","start":"2026-05-27T13:57:25.311246Z","end":"2026-05-27T13:57:25.437058Z","steps":["trace[118126271] 'process raft request' (duration: 124.985149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:57:25.437114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.777904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T13:57:25.437195Z","caller":"traceutil/trace.go:171","msg":"trace[90348520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2132; }","duration":"159.923467ms","start":"2026-05-27T13:57:25.277252Z","end":"2026-05-27T13:57:25.437175Z","steps":["trace[90348520] 'agreement among raft nodes before linearized reading' (duration: 159.754983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:58:15.989588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.920974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-27T13:58:15.989678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.930564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-27T13:58:15.989678Z","caller":"traceutil/trace.go:171","msg":"trace[781848646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2384; }","duration":"208.046687ms","start":"2026-05-27T13:58:15.781609Z","end":"2026-05-27T13:58:15.989656Z","steps":["trace[781848646] 'range keys from in-memory index tree' (duration: 207.734649ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T13:58:15.989712Z","caller":"traceutil/trace.go:171","msg":"trace[756725718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2384; }","duration":"140.988996ms","start":"2026-05-27T13:58:15.848709Z","end":"2026-05-27T13:58:15.989698Z","steps":["trace[756725718] 'range keys from in-memory index tree' (duration: 140.858912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:58:15.989572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.709486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T13:58:15.98976Z","caller":"traceutil/trace.go:171","msg":"trace[144652622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2384; }","duration":"132.934021ms","start":"2026-05-27T13:58:15.856817Z","end":"2026-05-27T13:58:15.989751Z","steps":["trace[144652622] 'range keys from in-memory index tree' (duration: 132.604343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T13:58:15.989601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.778276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T13:58:15.989853Z","caller":"traceutil/trace.go:171","msg":"trace[1692675169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2384; }","duration":"140.076214ms","start":"2026-05-27T13:58:15.849746Z","end":"2026-05-27T13:58:15.989822Z","steps":["trace[1692675169] 'range keys from in-memory index tree' (duration: 139.611273ms)"],"step_count":1}