{"level":"warn","ts":"2026-02-09T10:54:40.176665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-09T10:54:40.176761Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-02-09T10:54:40.176994Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-09T10:54:40.177012Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-09T10:54:40.177033Z","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-02-09T10:54:40.177763Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-09T10:54:40.185382Z","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":2,"max-cpu-available":2,"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://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-02-09T10:54:40.189677Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.045135ms"} {"level":"info","ts":"2026-02-09T10:54:40.212083Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-09T10:54:40.212136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T10:54:40.21219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-09T10:54:40.212207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T10:54:40.212216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-09T10:54:40.212327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-09T10:54:40.222271Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T10:54:40.227738Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T10:54:40.231743Z","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-02-09T10:54:40.236807Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T10:54:40.244734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T10:54:40.245225Z","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-02-09T10:54:40.247837Z","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-02-09T10:54:40.254221Z","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-02-09T10:54:40.255431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-09T10:54:40.255586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-09T10:54:40.25544Z","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-02-09T10:54:40.255978Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T10:54:40.256089Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T10:54:40.255466Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-09T10:54:40.259067Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-09T10:54:41.112692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T10:54:41.112751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T10:54:41.112776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-09T10:54:41.112801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-09T10:54:41.11289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-09T10:54:41.113158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-09T10:54:41.113309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-09T10:54:41.114777Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T10:54:41.114929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T10:54:41.115168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T10:54:41.115559Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T10:54:41.115594Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T10:54:41.115664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:54:41.11835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T10:54:41.142293Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-09T10:54:41.14986Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:54:41.152403Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T10:54:41.152428Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-09T10:54:49.61056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.204948ms","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-02-09T10:54:49.610767Z","caller":"traceutil/trace.go:171","msg":"trace[660190267] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"208.452783ms","start":"2026-02-09T10:54:49.402301Z","end":"2026-02-09T10:54:49.610754Z","steps":["trace[660190267] 'range keys from in-memory index tree' (duration: 208.157166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:54:49.610992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.878813ms","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-02-09T10:54:49.611048Z","caller":"traceutil/trace.go:171","msg":"trace[153394328] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:303; }","duration":"157.958756ms","start":"2026-02-09T10:54:49.453082Z","end":"2026-02-09T10:54:49.611041Z","steps":["trace[153394328] 'range keys from in-memory index tree' (duration: 157.806132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:54:49.611178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.4183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-09T10:54:49.611241Z","caller":"traceutil/trace.go:171","msg":"trace[673054004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"108.520772ms","start":"2026-02-09T10:54:49.502713Z","end":"2026-02-09T10:54:49.611234Z","steps":["trace[673054004] 'range keys from in-memory index tree' (duration: 108.350308ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:54:49.858033Z","caller":"traceutil/trace.go:171","msg":"trace[1366257655] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"176.046411ms","start":"2026-02-09T10:54:49.68173Z","end":"2026-02-09T10:54:49.857777Z","steps":["trace[1366257655] 'process raft request' (duration: 146.908646ms)","trace[1366257655] 'compare' (duration: 29.049283ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:54:49.859093Z","caller":"traceutil/trace.go:171","msg":"trace[1770282847] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"156.01584ms","start":"2026-02-09T10:54:49.703061Z","end":"2026-02-09T10:54:49.859077Z","steps":["trace[1770282847] 'read index received' (duration: 125.661896ms)","trace[1770282847] 'applied index is now lower than readState.Index' (duration: 30.353174ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:54:49.859289Z","caller":"traceutil/trace.go:171","msg":"trace[785745530] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"174.164477ms","start":"2026-02-09T10:54:49.685114Z","end":"2026-02-09T10:54:49.859279Z","steps":["trace[785745530] 'process raft request' (duration: 173.89565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:54:49.859498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.423139ms","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-02-09T10:54:49.859538Z","caller":"traceutil/trace.go:171","msg":"trace[1979322129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:306; }","duration":"156.47677ms","start":"2026-02-09T10:54:49.703052Z","end":"2026-02-09T10:54:49.859529Z","steps":["trace[1979322129] 'agreement among raft nodes before linearized reading' (duration: 156.363468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:54:49.860855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.529986ms","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-02-09T10:54:49.860897Z","caller":"traceutil/trace.go:171","msg":"trace[333672508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"106.612407ms","start":"2026-02-09T10:54:49.754274Z","end":"2026-02-09T10:54:49.860887Z","steps":["trace[333672508] 'agreement among raft nodes before linearized reading' (duration: 106.527716ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:54:52.442173Z","caller":"traceutil/trace.go:171","msg":"trace[1584781896] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"140.840893ms","start":"2026-02-09T10:54:52.301313Z","end":"2026-02-09T10:54:52.442154Z","steps":["trace[1584781896] 'process raft request' (duration: 72.436614ms)","trace[1584781896] 'compare' (duration: 68.248315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:54:55.404419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644323830945595,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T10:54:55.517889Z","caller":"traceutil/trace.go:171","msg":"trace[1092270476] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"614.076564ms","start":"2026-02-09T10:54:54.90379Z","end":"2026-02-09T10:54:55.517866Z","steps":["trace[1092270476] 'read index received' (duration: 613.894299ms)","trace[1092270476] 'applied index is now lower than readState.Index' (duration: 181.425µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:54:55.518016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.204307ms","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-02-09T10:54:55.518095Z","caller":"traceutil/trace.go:171","msg":"trace[2147320693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"614.283579ms","start":"2026-02-09T10:54:54.903759Z","end":"2026-02-09T10:54:55.518043Z","steps":["trace[2147320693] 'agreement among raft nodes before linearized reading' (duration: 614.193007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:54:55.51814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:54:54.903744Z","time spent":"614.386351ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-09T10:54:55.5185Z","caller":"traceutil/trace.go:171","msg":"trace[1804553815] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"642.120473ms","start":"2026-02-09T10:54:54.876369Z","end":"2026-02-09T10:54:55.518489Z","steps":["trace[1804553815] 'process raft request' (duration: 641.371146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:54:55.518987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:54:54.876353Z","time spent":"642.178695ms","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:13.424136Z","caller":"traceutil/trace.go:171","msg":"trace[2032862617] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"140.545646ms","start":"2026-02-09T10:55:13.283576Z","end":"2026-02-09T10:55:13.424122Z","steps":["trace[2032862617] 'process raft request' (duration: 140.449554ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:14.169531Z","caller":"traceutil/trace.go:171","msg":"trace[611160185] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"216.072163ms","start":"2026-02-09T10:55:13.953444Z","end":"2026-02-09T10:55:14.169516Z","steps":["trace[611160185] 'read index received' (duration: 215.96491ms)","trace[611160185] 'applied index is now lower than readState.Index' (duration: 106.773µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:55:14.169709Z","caller":"traceutil/trace.go:171","msg":"trace[591332805] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"347.226587ms","start":"2026-02-09T10:55:13.822475Z","end":"2026-02-09T10:55:14.169702Z","steps":["trace[591332805] 'process raft request' (duration: 346.969711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:14.169768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:13.822464Z","time spent":"347.263349ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:55:14.169871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.427221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T10:55:14.169903Z","caller":"traceutil/trace.go:171","msg":"trace[1342212037] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:478; }","duration":"216.493043ms","start":"2026-02-09T10:55:13.953404Z","end":"2026-02-09T10:55:14.169897Z","steps":["trace[1342212037] 'agreement among raft nodes before linearized reading' (duration: 216.447701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:14.169982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.950209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:55:14.169999Z","caller":"traceutil/trace.go:171","msg":"trace[1142841747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"124.99272ms","start":"2026-02-09T10:55:14.045001Z","end":"2026-02-09T10:55:14.169994Z","steps":["trace[1142841747] 'agreement among raft nodes before linearized reading' (duration: 124.970369ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:14.795764Z","caller":"traceutil/trace.go:171","msg":"trace[1236083887] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"154.873153ms","start":"2026-02-09T10:55:14.640876Z","end":"2026-02-09T10:55:14.795749Z","steps":["trace[1236083887] 'process raft request' (duration: 154.769551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:15.477325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.056464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:55:15.477447Z","caller":"traceutil/trace.go:171","msg":"trace[1257528330] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"835.967104ms","start":"2026-02-09T10:55:14.64147Z","end":"2026-02-09T10:55:15.477437Z","steps":["trace[1257528330] 'process raft request' (duration: 835.910792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:15.477482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:14.641466Z","time spent":"836.000724ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:15.477509Z","caller":"traceutil/trace.go:171","msg":"trace[483184844] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"836.070206ms","start":"2026-02-09T10:55:14.641417Z","end":"2026-02-09T10:55:15.477487Z","steps":["trace[483184844] 'process raft request' (duration: 400.719795ms)","trace[483184844] 'compare' (duration: 434.979552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:15.477607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:14.64141Z","time spent":"836.143397ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:55:20.772322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644323830945856,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T10:55:20.796295Z","caller":"traceutil/trace.go:171","msg":"trace[1873422613] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"524.983458ms","start":"2026-02-09T10:55:20.271293Z","end":"2026-02-09T10:55:20.796276Z","steps":["trace[1873422613] 'read index received' (duration: 524.823825ms)","trace[1873422613] 'applied index is now lower than readState.Index' (duration: 158.933µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:20.796541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.236694ms","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-02-09T10:55:20.796755Z","caller":"traceutil/trace.go:171","msg":"trace[452725106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"525.314816ms","start":"2026-02-09T10:55:20.271247Z","end":"2026-02-09T10:55:20.796562Z","steps":["trace[452725106] 'agreement among raft nodes before linearized reading' (duration: 525.163873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:20.796815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:20.271228Z","time spent":"525.558131ms","remote":"127.0.0.1:38118","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-02-09T10:55:20.79706Z","caller":"traceutil/trace.go:171","msg":"trace[1882165733] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"706.882177ms","start":"2026-02-09T10:55:20.09017Z","end":"2026-02-09T10:55:20.797052Z","steps":["trace[1882165733] 'process raft request' (duration: 706.012396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:20.797126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:20.090154Z","time spent":"706.930418ms","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:55:21.527365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.358384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:55:21.527495Z","caller":"traceutil/trace.go:171","msg":"trace[1225810650] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"709.010106ms","start":"2026-02-09T10:55:20.818466Z","end":"2026-02-09T10:55:21.527476Z","steps":["trace[1225810650] 'read index received' (duration: 465.409796ms)","trace[1225810650] 'applied index is now lower than readState.Index' (duration: 243.59903ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:55:21.527788Z","caller":"traceutil/trace.go:171","msg":"trace[500266798] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"723.533828ms","start":"2026-02-09T10:55:20.804236Z","end":"2026-02-09T10:55:21.52777Z","steps":["trace[500266798] 'process raft request' (duration: 479.686673ms)","trace[500266798] 'compare' (duration: 242.817701ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:21.527874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:20.804213Z","time spent":"723.6106ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:55:21.528424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.938049ms","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-02-09T10:55:21.528493Z","caller":"traceutil/trace.go:171","msg":"trace[2138119775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"710.04321ms","start":"2026-02-09T10:55:20.818433Z","end":"2026-02-09T10:55:21.528476Z","steps":["trace[2138119775] 'agreement among raft nodes before linearized reading' (duration: 709.916637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:21.528527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:20.81842Z","time spent":"710.098482ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T10:55:23.83399Z","caller":"traceutil/trace.go:171","msg":"trace[528755453] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"241.54619ms","start":"2026-02-09T10:55:23.592399Z","end":"2026-02-09T10:55:23.833946Z","steps":["trace[528755453] 'process raft request' (duration: 241.360866ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:23.838768Z","caller":"traceutil/trace.go:171","msg":"trace[1352107480] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"244.615323ms","start":"2026-02-09T10:55:23.594143Z","end":"2026-02-09T10:55:23.838758Z","steps":["trace[1352107480] 'process raft request' (duration: 244.236464ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:23.839105Z","caller":"traceutil/trace.go:171","msg":"trace[2036546649] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"244.680345ms","start":"2026-02-09T10:55:23.594316Z","end":"2026-02-09T10:55:23.838996Z","steps":["trace[2036546649] 'process raft request' (duration: 244.130522ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:33.077805Z","caller":"traceutil/trace.go:171","msg":"trace[1323596153] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"124.067419ms","start":"2026-02-09T10:55:32.953718Z","end":"2026-02-09T10:55:33.077786Z","steps":["trace[1323596153] 'process raft request' (duration: 123.749731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:34.516835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.059462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:55:34.516998Z","caller":"traceutil/trace.go:171","msg":"trace[625356675] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"247.641455ms","start":"2026-02-09T10:55:34.269339Z","end":"2026-02-09T10:55:34.51698Z","steps":["trace[625356675] 'process raft request' (duration: 65.341898ms)","trace[625356675] 'compare' (duration: 181.431707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:35.334336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.789471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:55:35.334442Z","caller":"traceutil/trace.go:171","msg":"trace[1078050530] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"1.064434212s","start":"2026-02-09T10:55:34.269997Z","end":"2026-02-09T10:55:35.334431Z","steps":["trace[1078050530] 'read index received' (duration: 64.641201ms)","trace[1078050530] 'applied index is now lower than readState.Index' (duration: 999.792441ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:55:35.334497Z","caller":"traceutil/trace.go:171","msg":"trace[387677597] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.065066558s","start":"2026-02-09T10:55:34.269424Z","end":"2026-02-09T10:55:35.33449Z","steps":["trace[387677597] 'process raft request' (duration: 559.068602ms)","trace[387677597] 'compare' (duration: 505.713279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:35.334524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:34.269419Z","time spent":"1.065090428s","remote":"127.0.0.1:38316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:55:35.334646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.868083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:55:35.33466Z","caller":"traceutil/trace.go:171","msg":"trace[166427270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"292.882104ms","start":"2026-02-09T10:55:35.041772Z","end":"2026-02-09T10:55:35.334655Z","steps":["trace[166427270] 'agreement among raft nodes before linearized reading' (duration: 292.858863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.334694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064675138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-977df\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-09T10:55:35.334759Z","caller":"traceutil/trace.go:171","msg":"trace[1903351898] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-977df; range_end:; response_count:1; response_revision:650; }","duration":"1.064760381s","start":"2026-02-09T10:55:34.269982Z","end":"2026-02-09T10:55:35.334742Z","steps":["trace[1903351898] 'agreement among raft nodes before linearized reading' (duration: 1.064572406s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.334798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:34.269967Z","time spent":"1.064822192s","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4720,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-977df\" "} {"level":"info","ts":"2026-02-09T10:55:35.334898Z","caller":"traceutil/trace.go:171","msg":"trace[423761375] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.059524525s","start":"2026-02-09T10:55:34.275364Z","end":"2026-02-09T10:55:35.334889Z","steps":["trace[423761375] 'process raft request' (duration: 1.059030423s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.334974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:34.275358Z","time spent":"1.059562977s","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:35.754829Z","caller":"traceutil/trace.go:171","msg":"trace[723028752] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"413.89798ms","start":"2026-02-09T10:55:35.340911Z","end":"2026-02-09T10:55:35.754809Z","steps":["trace[723028752] 'process raft request' (duration: 413.782857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.754995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.340886Z","time spent":"414.005053ms","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:35.755377Z","caller":"traceutil/trace.go:171","msg":"trace[1103703387] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:672; }","duration":"406.77346ms","start":"2026-02-09T10:55:35.348593Z","end":"2026-02-09T10:55:35.755367Z","steps":["trace[1103703387] 'read index received' (duration: 406.76979ms)","trace[1103703387] 'applied index is now lower than readState.Index' (duration: 2.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:35.755456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.855882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-977df\" ","response":"range_response_count:1 size:4709"} {"level":"info","ts":"2026-02-09T10:55:35.75549Z","caller":"traceutil/trace.go:171","msg":"trace[875392707] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-977df; range_end:; response_count:1; response_revision:651; }","duration":"406.920884ms","start":"2026-02-09T10:55:35.348561Z","end":"2026-02-09T10:55:35.755482Z","steps":["trace[875392707] 'agreement among raft nodes before linearized reading' (duration: 406.865773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.75552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.348556Z","time spent":"406.956195ms","remote":"127.0.0.1:38054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4732,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-977df\" "} {"level":"warn","ts":"2026-02-09T10:55:35.772483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.113511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-09T10:55:35.772529Z","caller":"traceutil/trace.go:171","msg":"trace[207637306] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:654; }","duration":"418.191023ms","start":"2026-02-09T10:55:35.354328Z","end":"2026-02-09T10:55:35.772519Z","steps":["trace[207637306] 'agreement among raft nodes before linearized reading' (duration: 418.06575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.772559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.354319Z","time spent":"418.235484ms","remote":"127.0.0.1:38316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4229,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-09T10:55:35.77281Z","caller":"traceutil/trace.go:171","msg":"trace[56025836] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"423.856559ms","start":"2026-02-09T10:55:35.348948Z","end":"2026-02-09T10:55:35.772804Z","steps":["trace[56025836] 'process raft request' (duration: 423.326126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.772862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.348939Z","time spent":"423.886199ms","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:35.772988Z","caller":"traceutil/trace.go:171","msg":"trace[1113160733] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"422.165878ms","start":"2026-02-09T10:55:35.350816Z","end":"2026-02-09T10:55:35.772982Z","steps":["trace[1113160733] 'process raft request' (duration: 421.527443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.773021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.350811Z","time spent":"422.194528ms","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:35.773131Z","caller":"traceutil/trace.go:171","msg":"trace[383346474] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"420.59247ms","start":"2026-02-09T10:55:35.352534Z","end":"2026-02-09T10:55:35.773126Z","steps":["trace[383346474] 'process raft request' (duration: 419.831482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:35.773155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.352517Z","time spent":"420.625341ms","remote":"127.0.0.1:38036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:55:35.773269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.956607ms","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-02-09T10:55:35.773283Z","caller":"traceutil/trace.go:171","msg":"trace[129560107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"118.999608ms","start":"2026-02-09T10:55:35.654279Z","end":"2026-02-09T10:55:35.773279Z","steps":["trace[129560107] 'agreement among raft nodes before linearized reading' (duration: 118.952637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:36.411971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.437111ms","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-02-09T10:55:36.41202Z","caller":"traceutil/trace.go:171","msg":"trace[11051999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"468.504332ms","start":"2026-02-09T10:55:35.943504Z","end":"2026-02-09T10:55:36.412009Z","steps":["trace[11051999] 'range keys from in-memory index tree' (duration: 468.347238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:36.412042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.943499Z","time spent":"468.539103ms","remote":"127.0.0.1:38118","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-02-09T10:55:36.412287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.429397ms","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-02-09T10:55:36.412301Z","caller":"traceutil/trace.go:171","msg":"trace[1412336104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"480.469867ms","start":"2026-02-09T10:55:35.931827Z","end":"2026-02-09T10:55:36.412296Z","steps":["trace[1412336104] 'range keys from in-memory index tree' (duration: 480.369635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:36.412312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.931813Z","time spent":"480.496208ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T10:55:36.412488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.195245ms","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-02-09T10:55:36.41252Z","caller":"traceutil/trace.go:171","msg":"trace[351654505] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:662; }","duration":"307.229926ms","start":"2026-02-09T10:55:36.105286Z","end":"2026-02-09T10:55:36.412516Z","steps":["trace[351654505] 'range keys from in-memory index tree' (duration: 307.189945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:36.412653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.370846ms","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-02-09T10:55:36.412666Z","caller":"traceutil/trace.go:171","msg":"trace[1765383404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"457.402467ms","start":"2026-02-09T10:55:35.95526Z","end":"2026-02-09T10:55:36.412662Z","steps":["trace[1765383404] 'range keys from in-memory index tree' (duration: 457.268474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:36.412679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:35.955248Z","time spent":"457.427658ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T10:55:36.412822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.972688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T10:55:36.412835Z","caller":"traceutil/trace.go:171","msg":"trace[1042535136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:662; }","duration":"252.01481ms","start":"2026-02-09T10:55:36.160817Z","end":"2026-02-09T10:55:36.412832Z","steps":["trace[1042535136] 'range keys from in-memory index tree' (duration: 251.871536ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:36.886878Z","caller":"traceutil/trace.go:171","msg":"trace[1272698209] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"470.05589ms","start":"2026-02-09T10:55:36.416805Z","end":"2026-02-09T10:55:36.886861Z","steps":["trace[1272698209] 'process raft request' (duration: 469.948887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:36.886978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:36.416792Z","time spent":"470.131161ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:37.025758Z","caller":"traceutil/trace.go:171","msg":"trace[1107731082] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"606.324329ms","start":"2026-02-09T10:55:36.419419Z","end":"2026-02-09T10:55:37.025743Z","steps":["trace[1107731082] 'process raft request' (duration: 606.300388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:37.025849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:36.419413Z","time spent":"606.38657ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:37.026031Z","caller":"traceutil/trace.go:171","msg":"trace[515343426] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"608.159142ms","start":"2026-02-09T10:55:36.417864Z","end":"2026-02-09T10:55:37.026023Z","steps":["trace[515343426] 'process raft request' (duration: 582.357317ms)","trace[515343426] 'compare' (duration: 25.091748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:37.026065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:36.417858Z","time spent":"608.188403ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:37.026141Z","caller":"traceutil/trace.go:171","msg":"trace[1653755301] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"608.238004ms","start":"2026-02-09T10:55:36.417897Z","end":"2026-02-09T10:55:37.026135Z","steps":["trace[1653755301] 'process raft request' (duration: 607.788473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:37.02617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:36.417893Z","time spent":"608.260965ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:43.048181Z","caller":"traceutil/trace.go:171","msg":"trace[1626445905] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"143.826299ms","start":"2026-02-09T10:55:42.904332Z","end":"2026-02-09T10:55:43.048158Z","steps":["trace[1626445905] 'process raft request' (duration: 143.484261ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:43.580972Z","caller":"traceutil/trace.go:171","msg":"trace[1088381789] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"524.801655ms","start":"2026-02-09T10:55:43.056162Z","end":"2026-02-09T10:55:43.580963Z","steps":["trace[1088381789] 'process raft request' (duration: 438.981048ms)","trace[1088381789] 'compare' (duration: 85.385747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:43.581075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:43.056144Z","time spent":"524.871306ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:43.580856Z","caller":"traceutil/trace.go:171","msg":"trace[1563781851] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"514.493719ms","start":"2026-02-09T10:55:43.066345Z","end":"2026-02-09T10:55:43.580839Z","steps":["trace[1563781851] 'read index received' (duration: 428.775215ms)","trace[1563781851] 'applied index is now lower than readState.Index' (duration: 85.717884ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:55:43.581468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.118654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T10:55:43.581493Z","caller":"traceutil/trace.go:171","msg":"trace[1402889357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"515.156854ms","start":"2026-02-09T10:55:43.066326Z","end":"2026-02-09T10:55:43.581483Z","steps":["trace[1402889357] 'agreement among raft nodes before linearized reading' (duration: 515.060842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:43.581511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:43.06632Z","time spent":"515.187255ms","remote":"127.0.0.1:38118","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-02-09T10:55:43.582933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.24395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T10:55:43.582957Z","caller":"traceutil/trace.go:171","msg":"trace[392068008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"508.29393ms","start":"2026-02-09T10:55:43.074657Z","end":"2026-02-09T10:55:43.582951Z","steps":["trace[392068008] 'agreement among raft nodes before linearized reading' (duration: 508.216199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:43.582976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:43.074648Z","time spent":"508.323172ms","remote":"127.0.0.1:38118","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-02-09T10:55:43.583071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.657209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:55:43.583089Z","caller":"traceutil/trace.go:171","msg":"trace[1686143539] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:682; }","duration":"370.67709ms","start":"2026-02-09T10:55:43.212407Z","end":"2026-02-09T10:55:43.583084Z","steps":["trace[1686143539] 'agreement among raft nodes before linearized reading' (duration: 370.646459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:43.583105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:43.212372Z","time spent":"370.728191ms","remote":"127.0.0.1:38152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-09T10:55:43.885766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.779136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:55:43.886223Z","caller":"traceutil/trace.go:171","msg":"trace[786615219] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"301.805206ms","start":"2026-02-09T10:55:43.5844Z","end":"2026-02-09T10:55:43.886205Z","steps":["trace[786615219] 'process raft request' (duration: 155.468897ms)","trace[786615219] 'compare' (duration: 145.714895ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:55:43.886244Z","caller":"traceutil/trace.go:171","msg":"trace[424244337] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"299.585244ms","start":"2026-02-09T10:55:43.586642Z","end":"2026-02-09T10:55:43.886227Z","steps":["trace[424244337] 'process raft request' (duration: 299.204515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:55:43.886316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:55:43.58438Z","time spent":"301.882849ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:55:43.993637Z","caller":"traceutil/trace.go:171","msg":"trace[546818996] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"103.16935ms","start":"2026-02-09T10:55:43.890453Z","end":"2026-02-09T10:55:43.993622Z","steps":["trace[546818996] 'process raft request' (duration: 101.223283ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:45.798075Z","caller":"traceutil/trace.go:171","msg":"trace[1697965484] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"208.047161ms","start":"2026-02-09T10:55:45.590012Z","end":"2026-02-09T10:55:45.79806Z","steps":["trace[1697965484] 'process raft request' (duration: 207.936748ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:46.024885Z","caller":"traceutil/trace.go:171","msg":"trace[141392525] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"105.804813ms","start":"2026-02-09T10:55:45.91906Z","end":"2026-02-09T10:55:46.024865Z","steps":["trace[141392525] 'process raft request' (duration: 105.366943ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:47.997515Z","caller":"traceutil/trace.go:171","msg":"trace[634118139] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"115.525245ms","start":"2026-02-09T10:55:47.881966Z","end":"2026-02-09T10:55:47.997491Z","steps":["trace[634118139] 'process raft request' (duration: 115.351681ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:55:52.282483Z","caller":"traceutil/trace.go:171","msg":"trace[1804775912] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"124.388596ms","start":"2026-02-09T10:55:52.158074Z","end":"2026-02-09T10:55:52.282463Z","steps":["trace[1804775912] 'process raft request' (duration: 87.023396ms)","trace[1804775912] 'compare' (duration: 37.280278ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:00.094811Z","caller":"traceutil/trace.go:171","msg":"trace[2145838058] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"209.288891ms","start":"2026-02-09T10:55:59.88551Z","end":"2026-02-09T10:56:00.094799Z","steps":["trace[2145838058] 'process raft request' (duration: 209.029895ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:01.531808Z","caller":"traceutil/trace.go:171","msg":"trace[1743902068] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"256.818214ms","start":"2026-02-09T10:56:01.274971Z","end":"2026-02-09T10:56:01.531789Z","steps":["trace[1743902068] 'read index received' (duration: 256.684681ms)","trace[1743902068] 'applied index is now lower than readState.Index' (duration: 132.933µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:01.53195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.958817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T10:56:01.531981Z","caller":"traceutil/trace.go:171","msg":"trace[1114953522] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:738; }","duration":"257.041689ms","start":"2026-02-09T10:56:01.274931Z","end":"2026-02-09T10:56:01.531973Z","steps":["trace[1114953522] 'agreement among raft nodes before linearized reading' (duration: 256.926247ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:01.532189Z","caller":"traceutil/trace.go:171","msg":"trace[1545642898] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"416.569763ms","start":"2026-02-09T10:56:01.115612Z","end":"2026-02-09T10:56:01.532182Z","steps":["trace[1545642898] 'process raft request' (duration: 416.086522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:01.53225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:01.115604Z","time spent":"416.606635ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:56:03.668787Z","caller":"traceutil/trace.go:171","msg":"trace[764600673] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"129.067798ms","start":"2026-02-09T10:56:03.539382Z","end":"2026-02-09T10:56:03.66845Z","steps":["trace[764600673] 'process raft request' (duration: 128.909055ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:05.36952Z","caller":"traceutil/trace.go:171","msg":"trace[647838640] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"324.685162ms","start":"2026-02-09T10:56:05.044818Z","end":"2026-02-09T10:56:05.369503Z","steps":["trace[647838640] 'read index received' (duration: 324.504658ms)","trace[647838640] 'applied index is now lower than readState.Index' (duration: 179.974µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:05.370462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.623145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:05.370505Z","caller":"traceutil/trace.go:171","msg":"trace[1946952156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"325.710567ms","start":"2026-02-09T10:56:05.044787Z","end":"2026-02-09T10:56:05.370498Z","steps":["trace[1946952156] 'agreement among raft nodes before linearized reading' (duration: 325.605954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:05.37054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:05.044771Z","time spent":"325.762508ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T10:56:05.371769Z","caller":"traceutil/trace.go:171","msg":"trace[1976102637] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"483.297195ms","start":"2026-02-09T10:56:04.888463Z","end":"2026-02-09T10:56:05.37176Z","steps":["trace[1976102637] 'process raft request' (duration: 480.906448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:05.372414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:04.888439Z","time spent":"483.93029ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:06.332304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.032134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:06.33238Z","caller":"traceutil/trace.go:171","msg":"trace[416872378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"287.167828ms","start":"2026-02-09T10:56:06.045193Z","end":"2026-02-09T10:56:06.332361Z","steps":["trace[416872378] 'range keys from in-memory index tree' (duration: 286.959873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:06.332743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.081499ms","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-02-09T10:56:06.332809Z","caller":"traceutil/trace.go:171","msg":"trace[260842721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"660.192922ms","start":"2026-02-09T10:56:05.672568Z","end":"2026-02-09T10:56:06.332791Z","steps":["trace[260842721] 'range keys from in-memory index tree' (duration: 659.845024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:06.332853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:05.672464Z","time spent":"660.374026ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T10:56:06.332832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.981032ms","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-02-09T10:56:06.332987Z","caller":"traceutil/trace.go:171","msg":"trace[2095290753] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:749; }","duration":"227.144526ms","start":"2026-02-09T10:56:06.10583Z","end":"2026-02-09T10:56:06.332975Z","steps":["trace[2095290753] 'range keys from in-memory index tree' (duration: 226.970982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:06.333243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.369035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T10:56:06.333296Z","caller":"traceutil/trace.go:171","msg":"trace[743661740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:749; }","duration":"194.483248ms","start":"2026-02-09T10:56:06.138803Z","end":"2026-02-09T10:56:06.333286Z","steps":["trace[743661740] 'range keys from in-memory index tree' (duration: 194.196311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:06.3334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.185037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-09T10:56:06.333435Z","caller":"traceutil/trace.go:171","msg":"trace[177969926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"110.26858ms","start":"2026-02-09T10:56:06.223156Z","end":"2026-02-09T10:56:06.333425Z","steps":["trace[177969926] 'range keys from in-memory index tree' (duration: 110.043714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:06.333668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T10:56:06.333696Z","caller":"traceutil/trace.go:171","msg":"trace[535142408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"102.130385ms","start":"2026-02-09T10:56:06.231557Z","end":"2026-02-09T10:56:06.333688Z","steps":["trace[535142408] 'range keys from in-memory index tree' (duration: 101.962472ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:06.543191Z","caller":"traceutil/trace.go:171","msg":"trace[1648106728] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"202.209622ms","start":"2026-02-09T10:56:06.340957Z","end":"2026-02-09T10:56:06.543167Z","steps":["trace[1648106728] 'process raft request' (duration: 202.158301ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:06.543638Z","caller":"traceutil/trace.go:171","msg":"trace[1071834965] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"202.686623ms","start":"2026-02-09T10:56:06.34094Z","end":"2026-02-09T10:56:06.543627Z","steps":["trace[1071834965] 'process raft request' (duration: 202.030567ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:06.551357Z","caller":"traceutil/trace.go:171","msg":"trace[1893069390] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"134.121669ms","start":"2026-02-09T10:56:06.417215Z","end":"2026-02-09T10:56:06.551337Z","steps":["trace[1893069390] 'read index received' (duration: 126.759813ms)","trace[1893069390] 'applied index is now lower than readState.Index' (duration: 7.360956ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:06.551422Z","caller":"traceutil/trace.go:171","msg":"trace[719331013] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"208.776308ms","start":"2026-02-09T10:56:06.342636Z","end":"2026-02-09T10:56:06.551413Z","steps":["trace[719331013] 'process raft request' (duration: 208.577084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:06.551536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.303873ms","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-02-09T10:56:06.551561Z","caller":"traceutil/trace.go:171","msg":"trace[704188740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"134.375415ms","start":"2026-02-09T10:56:06.417178Z","end":"2026-02-09T10:56:06.551554Z","steps":["trace[704188740] 'agreement among raft nodes before linearized reading' (duration: 134.236212ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:06.551626Z","caller":"traceutil/trace.go:171","msg":"trace[479674727] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"207.755904ms","start":"2026-02-09T10:56:06.343864Z","end":"2026-02-09T10:56:06.55162Z","steps":["trace[479674727] 'process raft request' (duration: 207.440127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.139538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644323830946616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T10:56:19.186688Z","caller":"traceutil/trace.go:171","msg":"trace[1695708978] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"547.804634ms","start":"2026-02-09T10:56:18.638856Z","end":"2026-02-09T10:56:19.186661Z","steps":["trace[1695708978] 'read index received' (duration: 547.6483ms)","trace[1695708978] 'applied index is now lower than readState.Index' (duration: 155.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:19.186893Z","caller":"traceutil/trace.go:171","msg":"trace[1826081541] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"561.08444ms","start":"2026-02-09T10:56:18.625802Z","end":"2026-02-09T10:56:19.186887Z","steps":["trace[1826081541] 'process raft request' (duration: 560.761332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.186992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:18.625787Z","time spent":"561.144011ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:19.187164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.306725ms","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-02-09T10:56:19.187181Z","caller":"traceutil/trace.go:171","msg":"trace[105852470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"548.345616ms","start":"2026-02-09T10:56:18.638831Z","end":"2026-02-09T10:56:19.187176Z","steps":["trace[105852470] 'agreement among raft nodes before linearized reading' (duration: 548.265184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.187196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:18.638822Z","time spent":"548.371127ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T10:56:19.187342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.13743ms","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-02-09T10:56:19.187367Z","caller":"traceutil/trace.go:171","msg":"trace[488490916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"532.182ms","start":"2026-02-09T10:56:18.655181Z","end":"2026-02-09T10:56:19.187363Z","steps":["trace[488490916] 'agreement among raft nodes before linearized reading' (duration: 532.128169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.18738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:18.655172Z","time spent":"532.204861ms","remote":"127.0.0.1:38118","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-02-09T10:56:19.188043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.660209ms","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-02-09T10:56:19.188062Z","caller":"traceutil/trace.go:171","msg":"trace[881116865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:835; }","duration":"521.722781ms","start":"2026-02-09T10:56:18.666334Z","end":"2026-02-09T10:56:19.188057Z","steps":["trace[881116865] 'agreement among raft nodes before linearized reading' (duration: 521.217399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.188077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:18.666313Z","time spent":"521.758962ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T10:56:19.188232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.707214ms","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-02-09T10:56:19.188247Z","caller":"traceutil/trace.go:171","msg":"trace[752303532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"527.759505ms","start":"2026-02-09T10:56:18.660483Z","end":"2026-02-09T10:56:19.188242Z","steps":["trace[752303532] 'agreement among raft nodes before linearized reading' (duration: 527.663993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.18826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:18.660457Z","time spent":"527.800156ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T10:56:19.60586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.650469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:56:19.605922Z","caller":"traceutil/trace.go:171","msg":"trace[1180654860] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"414.72856ms","start":"2026-02-09T10:56:19.191181Z","end":"2026-02-09T10:56:19.60591Z","steps":["trace[1180654860] 'process raft request' (duration: 186.978649ms)","trace[1180654860] 'compare' (duration: 227.588657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:19.605956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:19.191162Z","time spent":"414.778251ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:19.826127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.330887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:56:19.828392Z","caller":"traceutil/trace.go:171","msg":"trace[175015998] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"635.934885ms","start":"2026-02-09T10:56:19.192426Z","end":"2026-02-09T10:56:19.828361Z","steps":["trace[175015998] 'process raft request' (duration: 524.316583ms)","trace[175015998] 'compare' (duration: 109.233295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:19.828534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:19.192415Z","time spent":"636.042427ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:56:19.828959Z","caller":"traceutil/trace.go:171","msg":"trace[1192889074] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"636.478638ms","start":"2026-02-09T10:56:19.192469Z","end":"2026-02-09T10:56:19.828948Z","steps":["trace[1192889074] 'process raft request' (duration: 633.716522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.829021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:19.192464Z","time spent":"636.525659ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:56:19.829229Z","caller":"traceutil/trace.go:171","msg":"trace[1754713402] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"635.479024ms","start":"2026-02-09T10:56:19.193742Z","end":"2026-02-09T10:56:19.829221Z","steps":["trace[1754713402] 'process raft request' (duration: 632.466372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:19.829283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:19.193736Z","time spent":"635.517325ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:19.932298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.061649ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T10:56:19.93236Z","caller":"traceutil/trace.go:171","msg":"trace[1282558697] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:867; }","duration":"277.069257ms","start":"2026-02-09T10:56:19.655279Z","end":"2026-02-09T10:56:19.932348Z","steps":["trace[1282558697] 'read index received' (duration: 61.421605ms)","trace[1282558697] 'applied index is now lower than readState.Index' (duration: 215.646772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:19.932423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.133189ms","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-02-09T10:56:19.932435Z","caller":"traceutil/trace.go:171","msg":"trace[80708051] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:839; }","duration":"277.179339ms","start":"2026-02-09T10:56:19.655251Z","end":"2026-02-09T10:56:19.932431Z","steps":["trace[80708051] 'agreement among raft nodes before linearized reading' (duration: 277.123348ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:25.328225Z","caller":"traceutil/trace.go:171","msg":"trace[2102352266] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"119.150561ms","start":"2026-02-09T10:56:25.209056Z","end":"2026-02-09T10:56:25.328207Z","steps":["trace[2102352266] 'process raft request' (duration: 119.061119ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:25.870786Z","caller":"traceutil/trace.go:171","msg":"trace[2128750966] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"250.116855ms","start":"2026-02-09T10:56:25.620649Z","end":"2026-02-09T10:56:25.870766Z","steps":["trace[2128750966] 'process raft request' (duration: 249.860288ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:28.138885Z","caller":"traceutil/trace.go:171","msg":"trace[66418035] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"109.305205ms","start":"2026-02-09T10:56:28.029564Z","end":"2026-02-09T10:56:28.138869Z","steps":["trace[66418035] 'process raft request' (duration: 68.910998ms)","trace[66418035] 'compare' (duration: 40.112851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:32.357337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.449311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:56:32.357397Z","caller":"traceutil/trace.go:171","msg":"trace[786023775] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"316.52125ms","start":"2026-02-09T10:56:32.040865Z","end":"2026-02-09T10:56:32.357387Z","steps":["trace[786023775] 'read index received' (duration: 136.919655ms)","trace[786023775] 'applied index is now lower than readState.Index' (duration: 179.600925ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:32.357506Z","caller":"traceutil/trace.go:171","msg":"trace[1357848662] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"365.742301ms","start":"2026-02-09T10:56:31.991757Z","end":"2026-02-09T10:56:32.3575Z","steps":["trace[1357848662] 'process raft request' (duration: 186.069685ms)","trace[1357848662] 'compare' (duration: 179.218156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:32.357534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:31.991747Z","time spent":"365.772682ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:32.357666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.800336ms","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-02-09T10:56:32.357679Z","caller":"traceutil/trace.go:171","msg":"trace[568198789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"316.837698ms","start":"2026-02-09T10:56:32.040837Z","end":"2026-02-09T10:56:32.357674Z","steps":["trace[568198789] 'agreement among raft nodes before linearized reading' (duration: 316.777646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:32.357692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:32.040822Z","time spent":"316.867608ms","remote":"127.0.0.1:38118","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-02-09T10:56:32.357754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.54808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:32.357765Z","caller":"traceutil/trace.go:171","msg":"trace[446554813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"316.567752ms","start":"2026-02-09T10:56:32.041194Z","end":"2026-02-09T10:56:32.357761Z","steps":["trace[446554813] 'agreement among raft nodes before linearized reading' (duration: 316.549191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:32.357777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:32.041188Z","time spent":"316.587232ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T10:56:32.357902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.728943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T10:56:32.357917Z","caller":"traceutil/trace.go:171","msg":"trace[1407087403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"209.789735ms","start":"2026-02-09T10:56:32.148123Z","end":"2026-02-09T10:56:32.357913Z","steps":["trace[1407087403] 'agreement among raft nodes before linearized reading' (duration: 209.736854ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:32.529104Z","caller":"traceutil/trace.go:171","msg":"trace[823064681] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"167.781071ms","start":"2026-02-09T10:56:32.361305Z","end":"2026-02-09T10:56:32.529086Z","steps":["trace[823064681] 'read index received' (duration: 160.806569ms)","trace[823064681] 'applied index is now lower than readState.Index' (duration: 6.973691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:32.529181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.856322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:32.529196Z","caller":"traceutil/trace.go:171","msg":"trace[1167195279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"167.900183ms","start":"2026-02-09T10:56:32.361291Z","end":"2026-02-09T10:56:32.529191Z","steps":["trace[1167195279] 'agreement among raft nodes before linearized reading' (duration: 167.855382ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:32.529233Z","caller":"traceutil/trace.go:171","msg":"trace[1047731374] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"167.936525ms","start":"2026-02-09T10:56:32.36128Z","end":"2026-02-09T10:56:32.529217Z","steps":["trace[1047731374] 'process raft request' (duration: 160.781839ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:32.533678Z","caller":"traceutil/trace.go:171","msg":"trace[1885705514] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"171.028616ms","start":"2026-02-09T10:56:32.362642Z","end":"2026-02-09T10:56:32.53367Z","steps":["trace[1885705514] 'process raft request' (duration: 170.980665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:33.123594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.880123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:33.123655Z","caller":"traceutil/trace.go:171","msg":"trace[962099570] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:872; }","duration":"349.985896ms","start":"2026-02-09T10:56:32.773654Z","end":"2026-02-09T10:56:33.12364Z","steps":["trace[962099570] 'count revisions from in-memory index tree' (duration: 349.785261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:33.123685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:32.773637Z","time spent":"350.041277ms","remote":"127.0.0.1:33910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-09T10:56:33.653836Z","caller":"traceutil/trace.go:171","msg":"trace[49547100] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"241.91623ms","start":"2026-02-09T10:56:33.411895Z","end":"2026-02-09T10:56:33.653811Z","steps":["trace[49547100] 'process raft request' (duration: 241.296845ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:34.122772Z","caller":"traceutil/trace.go:171","msg":"trace[1554989059] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"131.761295ms","start":"2026-02-09T10:56:33.990989Z","end":"2026-02-09T10:56:34.12275Z","steps":["trace[1554989059] 'read index received' (duration: 131.616942ms)","trace[1554989059] 'applied index is now lower than readState.Index' (duration: 143.133µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:34.123529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.9489ms","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-02-09T10:56:34.123612Z","caller":"traceutil/trace.go:171","msg":"trace[734305845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"132.632286ms","start":"2026-02-09T10:56:33.990957Z","end":"2026-02-09T10:56:34.123589Z","steps":["trace[734305845] 'agreement among raft nodes before linearized reading' (duration: 131.897128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:34.416858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.944457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:56:34.417152Z","caller":"traceutil/trace.go:171","msg":"trace[484844291] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"292.051632ms","start":"2026-02-09T10:56:34.12505Z","end":"2026-02-09T10:56:34.417101Z","steps":["trace[484844291] 'process raft request' (duration: 147.779987ms)","trace[484844291] 'compare' (duration: 143.819494ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:34.417284Z","caller":"traceutil/trace.go:171","msg":"trace[468327518] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"289.678716ms","start":"2026-02-09T10:56:34.127586Z","end":"2026-02-09T10:56:34.417264Z","steps":["trace[468327518] 'process raft request' (duration: 289.522143ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:34.417636Z","caller":"traceutil/trace.go:171","msg":"trace[1120976250] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"292.384809ms","start":"2026-02-09T10:56:34.125242Z","end":"2026-02-09T10:56:34.417626Z","steps":["trace[1120976250] 'read index received' (duration: 147.52076ms)","trace[1120976250] 'applied index is now lower than readState.Index' (duration: 144.862969ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:34.417884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.625724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:34.417916Z","caller":"traceutil/trace.go:171","msg":"trace[1447048997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"292.685436ms","start":"2026-02-09T10:56:34.125223Z","end":"2026-02-09T10:56:34.417908Z","steps":["trace[1447048997] 'agreement among raft nodes before linearized reading' (duration: 292.473901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:34.663183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.002484ms","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-02-09T10:56:34.66326Z","caller":"traceutil/trace.go:171","msg":"trace[947722916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"130.112397ms","start":"2026-02-09T10:56:34.533129Z","end":"2026-02-09T10:56:34.663241Z","steps":["trace[947722916] 'range keys from in-memory index tree' (duration: 129.865281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:34.663517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.840842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-09T10:56:34.663555Z","caller":"traceutil/trace.go:171","msg":"trace[1964303354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"126.897393ms","start":"2026-02-09T10:56:34.536649Z","end":"2026-02-09T10:56:34.663546Z","steps":["trace[1964303354] 'range keys from in-memory index tree' (duration: 126.695208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:35.097645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.922981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T10:56:35.09774Z","caller":"traceutil/trace.go:171","msg":"trace[1854833507] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"313.685364ms","start":"2026-02-09T10:56:34.784038Z","end":"2026-02-09T10:56:35.097724Z","steps":["trace[1854833507] 'read index received' (duration: 171.534958ms)","trace[1854833507] 'applied index is now lower than readState.Index' (duration: 142.148946ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:35.097935Z","caller":"traceutil/trace.go:171","msg":"trace[936812024] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"425.144418ms","start":"2026-02-09T10:56:34.672779Z","end":"2026-02-09T10:56:35.097923Z","steps":["trace[936812024] 'process raft request' (duration: 282.860069ms)","trace[936812024] 'compare' (duration: 141.826659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:35.097994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:34.672761Z","time spent":"425.20168ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:35.098179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.151285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T10:56:35.09821Z","caller":"traceutil/trace.go:171","msg":"trace[306575543] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:878; }","duration":"314.236607ms","start":"2026-02-09T10:56:34.783962Z","end":"2026-02-09T10:56:35.098199Z","steps":["trace[306575543] 'agreement among raft nodes before linearized reading' (duration: 314.182566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:35.098239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:34.783933Z","time spent":"314.298529ms","remote":"127.0.0.1:33948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-09T10:56:35.098421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.248871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T10:56:35.098447Z","caller":"traceutil/trace.go:171","msg":"trace[1130905426] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:878; }","duration":"287.320473ms","start":"2026-02-09T10:56:34.811119Z","end":"2026-02-09T10:56:35.098439Z","steps":["trace[1130905426] 'agreement among raft nodes before linearized reading' (duration: 287.246212ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:36.551191Z","caller":"traceutil/trace.go:171","msg":"trace[1951710986] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"127.289562ms","start":"2026-02-09T10:56:36.423876Z","end":"2026-02-09T10:56:36.551165Z","steps":["trace[1951710986] 'read index received' (duration: 126.951654ms)","trace[1951710986] 'applied index is now lower than readState.Index' (duration: 336.678µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:36.551935Z","caller":"traceutil/trace.go:171","msg":"trace[1162827194] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"343.000153ms","start":"2026-02-09T10:56:36.208921Z","end":"2026-02-09T10:56:36.551921Z","steps":["trace[1162827194] 'process raft request' (duration: 342.090202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:36.553312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:36.208906Z","time spent":"344.333355ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T10:56:36.553787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.905662ms","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-02-09T10:56:36.554035Z","caller":"traceutil/trace.go:171","msg":"trace[1974229180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"130.202979ms","start":"2026-02-09T10:56:36.423815Z","end":"2026-02-09T10:56:36.554018Z","steps":["trace[1974229180] 'agreement among raft nodes before linearized reading' (duration: 129.874711ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:55.104674Z","caller":"traceutil/trace.go:171","msg":"trace[596979546] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"791.999406ms","start":"2026-02-09T10:56:54.312658Z","end":"2026-02-09T10:56:55.104658Z","steps":["trace[596979546] 'process raft request' (duration: 791.895093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:55.105718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:54.312641Z","time spent":"793.01756ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T10:56:55.105958Z","caller":"traceutil/trace.go:171","msg":"trace[365887857] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1006; }","duration":"337.19524ms","start":"2026-02-09T10:56:54.768754Z","end":"2026-02-09T10:56:55.105949Z","steps":["trace[365887857] 'read index received' (duration: 337.19216ms)","trace[365887857] 'applied index is now lower than readState.Index' (duration: 2.41µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T10:56:55.106194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.735478ms","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-02-09T10:56:55.10629Z","caller":"traceutil/trace.go:171","msg":"trace[444339723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"223.873001ms","start":"2026-02-09T10:56:54.882409Z","end":"2026-02-09T10:56:55.106282Z","steps":["trace[444339723] 'agreement among raft nodes before linearized reading' (duration: 223.718198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:55.106558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.791503ms","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-02-09T10:56:55.106796Z","caller":"traceutil/trace.go:171","msg":"trace[1765583324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"338.08544ms","start":"2026-02-09T10:56:54.768701Z","end":"2026-02-09T10:56:55.106787Z","steps":["trace[1765583324] 'agreement among raft nodes before linearized reading' (duration: 337.800233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T10:56:55.106887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T10:56:54.768677Z","time spent":"338.200243ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T10:56:55.107194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.977752ms","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-02-09T10:56:55.107428Z","caller":"traceutil/trace.go:171","msg":"trace[1649965934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"217.230478ms","start":"2026-02-09T10:56:54.890189Z","end":"2026-02-09T10:56:55.107419Z","steps":["trace[1649965934] 'agreement among raft nodes before linearized reading' (duration: 216.960641ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:55.265927Z","caller":"traceutil/trace.go:171","msg":"trace[824390821] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"154.707778ms","start":"2026-02-09T10:56:55.111194Z","end":"2026-02-09T10:56:55.265902Z","steps":["trace[824390821] 'process raft request' (duration: 116.435341ms)","trace[824390821] 'compare' (duration: 37.756255ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T10:56:55.266153Z","caller":"traceutil/trace.go:171","msg":"trace[558172601] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"152.786704ms","start":"2026-02-09T10:56:55.113356Z","end":"2026-02-09T10:56:55.266143Z","steps":["trace[558172601] 'process raft request' (duration: 152.452826ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T10:56:55.266328Z","caller":"traceutil/trace.go:171","msg":"trace[1151390156] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"151.917143ms","start":"2026-02-09T10:56:55.114401Z","end":"2026-02-09T10:56:55.266318Z","steps":["trace[1151390156] 'process raft request' (duration: 151.691447ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T11:00:20.670065Z","caller":"traceutil/trace.go:171","msg":"trace[275978472] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"196.115092ms","start":"2026-02-09T11:00:20.473932Z","end":"2026-02-09T11:00:20.670047Z","steps":["trace[275978472] 'read index received' (duration: 195.961358ms)","trace[275978472] 'applied index is now lower than readState.Index' (duration: 152.854µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T11:00:20.670338Z","caller":"traceutil/trace.go:171","msg":"trace[1260972258] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"332.691899ms","start":"2026-02-09T11:00:20.337634Z","end":"2026-02-09T11:00:20.670326Z","steps":["trace[1260972258] 'process raft request' (duration: 332.30561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:20.670446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.337624Z","time spent":"332.757951ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T11:00:20.670687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.752867ms","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-02-09T11:00:20.670726Z","caller":"traceutil/trace.go:171","msg":"trace[1086272279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"196.814477ms","start":"2026-02-09T11:00:20.473901Z","end":"2026-02-09T11:00:20.670715Z","steps":["trace[1086272279] 'agreement among raft nodes before linearized reading' (duration: 196.705685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:20.670868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.339057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T11:00:20.670903Z","caller":"traceutil/trace.go:171","msg":"trace[443575937] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1860; }","duration":"117.400469ms","start":"2026-02-09T11:00:20.553493Z","end":"2026-02-09T11:00:20.670893Z","steps":["trace[443575937] 'agreement among raft nodes before linearized reading' (duration: 117.349917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:20.671629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.741376ms","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-02-09T11:00:20.67167Z","caller":"traceutil/trace.go:171","msg":"trace[132422520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"108.795988ms","start":"2026-02-09T11:00:20.562865Z","end":"2026-02-09T11:00:20.67166Z","steps":["trace[132422520] 'agreement among raft nodes before linearized reading' (duration: 108.698005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.310819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.832748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T11:00:21.311832Z","caller":"traceutil/trace.go:171","msg":"trace[1047770644] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1941; }","duration":"530.49723ms","start":"2026-02-09T11:00:20.781317Z","end":"2026-02-09T11:00:21.311815Z","steps":["trace[1047770644] 'read index received' (duration: 423.535986ms)","trace[1047770644] 'applied index is now lower than readState.Index' (duration: 106.960314ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T11:00:21.312236Z","caller":"traceutil/trace.go:171","msg":"trace[1869530280] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"626.909858ms","start":"2026-02-09T11:00:20.685317Z","end":"2026-02-09T11:00:21.312227Z","steps":["trace[1869530280] 'process raft request' (duration: 626.464318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.312526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.215018ms","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-02-09T11:00:21.312554Z","caller":"traceutil/trace.go:171","msg":"trace[1864670514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1863; }","duration":"531.283819ms","start":"2026-02-09T11:00:20.781263Z","end":"2026-02-09T11:00:21.312547Z","steps":["trace[1864670514] 'agreement among raft nodes before linearized reading' (duration: 531.142125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.312602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.781232Z","time spent":"531.365521ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T11:00:21.312291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.685291Z","time spent":"626.973369ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T11:00:21.313177Z","caller":"traceutil/trace.go:171","msg":"trace[1200158929] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"629.938559ms","start":"2026-02-09T11:00:20.683228Z","end":"2026-02-09T11:00:21.313167Z","steps":["trace[1200158929] 'process raft request' (duration: 521.675484ms)","trace[1200158929] 'compare' (duration: 104.793714ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T11:00:21.313352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.609642ms","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-02-09T11:00:21.313377Z","caller":"traceutil/trace.go:171","msg":"trace[1996487606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1863; }","duration":"430.664853ms","start":"2026-02-09T11:00:20.882707Z","end":"2026-02-09T11:00:21.313372Z","steps":["trace[1996487606] 'agreement among raft nodes before linearized reading' (duration: 430.599951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.3134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.882678Z","time spent":"430.717964ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T11:00:21.313227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.683217Z","time spent":"629.98358ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T11:00:21.313772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.562261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T11:00:21.313798Z","caller":"traceutil/trace.go:171","msg":"trace[1655203692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"269.628983ms","start":"2026-02-09T11:00:21.044163Z","end":"2026-02-09T11:00:21.313792Z","steps":["trace[1655203692] 'agreement among raft nodes before linearized reading' (duration: 269.588932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.313912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.02536ms","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:536"} {"level":"info","ts":"2026-02-09T11:00:21.313928Z","caller":"traceutil/trace.go:171","msg":"trace[449559808] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1863; }","duration":"416.054431ms","start":"2026-02-09T11:00:20.897869Z","end":"2026-02-09T11:00:21.313923Z","steps":["trace[449559808] 'agreement among raft nodes before linearized reading' (duration: 416.003579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.313944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.897862Z","time spent":"416.079021ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-09T11:00:21.314041Z","caller":"traceutil/trace.go:171","msg":"trace[1992017467] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"630.239946ms","start":"2026-02-09T11:00:20.683334Z","end":"2026-02-09T11:00:21.313574Z","steps":["trace[1992017467] 'process raft request' (duration: 628.382902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T11:00:21.314079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T11:00:20.683329Z","time spent":"630.728097ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}