{"level":"warn","ts":"2026-02-27T14:47:34.161682Z","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-27T14:47:34.161804Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.225:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.225:2380","--initial-cluster=instance=https://199.204.45.225:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.225:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.225: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-27T14:47:34.16192Z","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-27T14:47:34.16194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.225:2380"]} {"level":"info","ts":"2026-02-27T14:47:34.161961Z","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-27T14:47:34.162916Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.225:2379"]} {"level":"info","ts":"2026-02-27T14:47:34.163094Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.225:2380"],"listen-peer-urls":["https://199.204.45.225:2380"],"advertise-client-urls":["https://199.204.45.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.225: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-27T14:47:34.167097Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.643791ms"} {"level":"info","ts":"2026-02-27T14:47:34.175104Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e68635a2746608d1","cluster-id":"2a7f8712d7dbceef"} {"level":"info","ts":"2026-02-27T14:47:34.1752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T14:47:34.175258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 became follower at term 0"} {"level":"info","ts":"2026-02-27T14:47:34.175278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e68635a2746608d1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T14:47:34.175295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 became follower at term 1"} {"level":"info","ts":"2026-02-27T14:47:34.175384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 switched to configuration voters=(16611023247456471249)"} {"level":"warn","ts":"2026-02-27T14:47:34.187468Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T14:47:34.18921Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T14:47:34.190721Z","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-27T14:47:34.194055Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e68635a2746608d1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T14:47:34.194246Z","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-27T14:47:34.19436Z","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-27T14:47:34.194314Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e68635a2746608d1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T14:47:34.194377Z","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-27T14:47:34.195416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 switched to configuration voters=(16611023247456471249)"} {"level":"info","ts":"2026-02-27T14:47:34.195786Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2a7f8712d7dbceef","local-member-id":"e68635a2746608d1","added-peer-id":"e68635a2746608d1","added-peer-peer-urls":["https://199.204.45.225:2380"]} {"level":"info","ts":"2026-02-27T14:47:34.198267Z","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-27T14:47:34.19835Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.225:2380"} {"level":"info","ts":"2026-02-27T14:47:34.198451Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.225:2380"} {"level":"info","ts":"2026-02-27T14:47:34.199337Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T14:47:34.199288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e68635a2746608d1","initial-advertise-peer-urls":["https://199.204.45.225:2380"],"listen-peer-urls":["https://199.204.45.225:2380"],"advertise-client-urls":["https://199.204.45.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T14:47:34.976168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T14:47:34.976232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T14:47:34.976256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 received MsgPreVoteResp from e68635a2746608d1 at term 1"} {"level":"info","ts":"2026-02-27T14:47:34.976286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 became candidate at term 2"} {"level":"info","ts":"2026-02-27T14:47:34.976298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 received MsgVoteResp from e68635a2746608d1 at term 2"} {"level":"info","ts":"2026-02-27T14:47:34.976321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e68635a2746608d1 became leader at term 2"} {"level":"info","ts":"2026-02-27T14:47:34.976334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e68635a2746608d1 elected leader e68635a2746608d1 at term 2"} {"level":"info","ts":"2026-02-27T14:47:34.982094Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e68635a2746608d1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.225:2379]}","request-path":"/0/members/e68635a2746608d1/attributes","cluster-id":"2a7f8712d7dbceef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T14:47:34.982145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T14:47:34.982254Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T14:47:34.982548Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T14:47:34.982666Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T14:47:34.982812Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:47:34.987311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.225:2379"} {"level":"info","ts":"2026-02-27T14:47:34.987753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T14:47:34.997809Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2a7f8712d7dbceef","local-member-id":"e68635a2746608d1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:47:34.997929Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:47:34.997955Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:47:44.678586Z","caller":"traceutil/trace.go:171","msg":"trace[489434880] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"143.699026ms","start":"2026-02-27T14:47:44.534854Z","end":"2026-02-27T14:47:44.678553Z","steps":["trace[489434880] 'process raft request' (duration: 126.528422ms)","trace[489434880] 'compare' (duration: 17.024991ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:47:50.047476Z","caller":"traceutil/trace.go:171","msg":"trace[2006507701] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"213.857888ms","start":"2026-02-27T14:47:49.8336Z","end":"2026-02-27T14:47:50.047458Z","steps":["trace[2006507701] 'process raft request' (duration: 213.755956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:51.16539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.747483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T14:47:51.165508Z","caller":"traceutil/trace.go:171","msg":"trace[1404379747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"858.913787ms","start":"2026-02-27T14:47:50.306574Z","end":"2026-02-27T14:47:51.165488Z","steps":["trace[1404379747] 'range keys from in-memory index tree' (duration: 858.605581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:51.165535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.527619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T14:47:51.165559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:50.306551Z","time spent":"858.99779ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T14:47:51.165637Z","caller":"traceutil/trace.go:171","msg":"trace[551169015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"376.623151ms","start":"2026-02-27T14:47:50.788956Z","end":"2026-02-27T14:47:51.165579Z","steps":["trace[551169015] 'range keys from in-memory index tree' (duration: 376.394737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:51.165716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:50.788928Z","time spent":"376.744164ms","remote":"127.0.0.1:34956","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-27T14:47:51.16594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.200328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-02-27T14:47:51.165925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.178924ms","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-27T14:47:51.165973Z","caller":"traceutil/trace.go:171","msg":"trace[1572188539] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:367; }","duration":"542.28983ms","start":"2026-02-27T14:47:50.623674Z","end":"2026-02-27T14:47:51.165964Z","steps":["trace[1572188539] 'range keys from in-memory index tree' (duration: 542.090175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:51.165924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.614962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-27T14:47:51.166001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:50.623653Z","time spent":"542.341961ms","remote":"127.0.0.1:35104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-27T14:47:51.166041Z","caller":"traceutil/trace.go:171","msg":"trace[1765659836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"750.770316ms","start":"2026-02-27T14:47:50.415243Z","end":"2026-02-27T14:47:51.166013Z","steps":["trace[1765659836] 'range keys from in-memory index tree' (duration: 750.50512ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:47:51.166075Z","caller":"traceutil/trace.go:171","msg":"trace[777955721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"742.362388ms","start":"2026-02-27T14:47:50.423695Z","end":"2026-02-27T14:47:51.166058Z","steps":["trace[777955721] 'range keys from in-memory index tree' (duration: 742.089662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:51.166087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:50.415226Z","time spent":"750.849068ms","remote":"127.0.0.1:34956","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-27T14:47:51.16613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:50.423684Z","time spent":"742.42221ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T14:47:51.283753Z","caller":"traceutil/trace.go:171","msg":"trace[347889038] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"111.66728ms","start":"2026-02-27T14:47:51.171983Z","end":"2026-02-27T14:47:51.28365Z","steps":["trace[347889038] 'process raft request' (duration: 111.488156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:51.825229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":635461231594709591,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T14:47:52.162927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.143672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:47:52.163291Z","caller":"traceutil/trace.go:171","msg":"trace[1682481907] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"991.142257ms","start":"2026-02-27T14:47:51.172082Z","end":"2026-02-27T14:47:52.163224Z","steps":["trace[1682481907] 'process raft request' (duration: 801.89264ms)","trace[1682481907] 'compare' (duration: 187.043227ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:47:52.163506Z","caller":"traceutil/trace.go:171","msg":"trace[995526668] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"991.138857ms","start":"2026-02-27T14:47:51.17235Z","end":"2026-02-27T14:47:52.163489Z","steps":["trace[995526668] 'process raft request' (duration: 990.83528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:52.163605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:51.172333Z","time spent":"991.215569ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:47:52.163621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.98004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-02-27T14:47:52.163668Z","caller":"traceutil/trace.go:171","msg":"trace[267790626] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:372; }","duration":"839.061603ms","start":"2026-02-27T14:47:51.324594Z","end":"2026-02-27T14:47:52.163655Z","steps":["trace[267790626] 'agreement among raft nodes before linearized reading' (duration: 838.94939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:52.163722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:51.324574Z","time spent":"839.137774ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-27T14:47:52.163862Z","caller":"traceutil/trace.go:171","msg":"trace[1859733738] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"981.316237ms","start":"2026-02-27T14:47:51.182535Z","end":"2026-02-27T14:47:52.163851Z","steps":["trace[1859733738] 'process raft request' (duration: 980.868437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:52.163927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:51.182526Z","time spent":"981.370999ms","remote":"127.0.0.1:35104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:47:52.163508Z","caller":"traceutil/trace.go:171","msg":"trace[1300886441] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:383; }","duration":"838.861018ms","start":"2026-02-27T14:47:51.324632Z","end":"2026-02-27T14:47:52.163493Z","steps":["trace[1300886441] 'read index received' (duration: 649.266363ms)","trace[1300886441] 'applied index is now lower than readState.Index' (duration: 189.593455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:47:52.1642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.657287ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:47:52.164304Z","caller":"traceutil/trace.go:171","msg":"trace[1721948565] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"655.758239ms","start":"2026-02-27T14:47:51.508529Z","end":"2026-02-27T14:47:52.164287Z","steps":["trace[1721948565] 'agreement among raft nodes before linearized reading' (duration: 655.641557ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:47:52.163326Z","caller":"traceutil/trace.go:171","msg":"trace[847960543] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"991.163998ms","start":"2026-02-27T14:47:51.17214Z","end":"2026-02-27T14:47:52.163304Z","steps":["trace[847960543] 'process raft request' (duration: 990.926623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:47:52.164729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:51.172104Z","time spent":"992.580539ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:47:52.16525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:47:51.172045Z","time spent":"991.374572ms","remote":"127.0.0.1:34956","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-27T14:47:52.275217Z","caller":"traceutil/trace.go:171","msg":"trace[535086219] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"105.073603ms","start":"2026-02-27T14:47:52.170119Z","end":"2026-02-27T14:47:52.275193Z","steps":["trace[535086219] 'process raft request' (duration: 103.364954ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:47:52.279707Z","caller":"traceutil/trace.go:171","msg":"trace[663739644] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"108.770595ms","start":"2026-02-27T14:47:52.170918Z","end":"2026-02-27T14:47:52.279689Z","steps":["trace[663739644] 'process raft request' (duration: 108.682962ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:07.576977Z","caller":"traceutil/trace.go:171","msg":"trace[1777251735] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"217.941989ms","start":"2026-02-27T14:48:07.359013Z","end":"2026-02-27T14:48:07.576955Z","steps":["trace[1777251735] 'process raft request' (duration: 217.809716ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:07.850839Z","caller":"traceutil/trace.go:171","msg":"trace[1767667364] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"256.644086ms","start":"2026-02-27T14:48:07.594169Z","end":"2026-02-27T14:48:07.850813Z","steps":["trace[1767667364] 'read index received' (duration: 202.173606ms)","trace[1767667364] 'applied index is now lower than readState.Index' (duration: 54.46935ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:07.850929Z","caller":"traceutil/trace.go:171","msg":"trace[394003633] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"440.963681ms","start":"2026-02-27T14:48:07.409936Z","end":"2026-02-27T14:48:07.8509Z","steps":["trace[394003633] 'process raft request' (duration: 386.367208ms)","trace[394003633] 'compare' (duration: 54.407678ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:48:07.850971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.787469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-02-27T14:48:07.85101Z","caller":"traceutil/trace.go:171","msg":"trace[237900807] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:473; }","duration":"256.865801ms","start":"2026-02-27T14:48:07.594133Z","end":"2026-02-27T14:48:07.850999Z","steps":["trace[237900807] 'agreement among raft nodes before linearized reading' (duration: 256.766169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:07.851063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:07.409911Z","time spent":"441.084974ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:48:14.625943Z","caller":"traceutil/trace.go:171","msg":"trace[2145195321] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"358.890433ms","start":"2026-02-27T14:48:14.267029Z","end":"2026-02-27T14:48:14.625919Z","steps":["trace[2145195321] 'process raft request' (duration: 358.75332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:14.626095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:14.266993Z","time spent":"359.024367ms","remote":"127.0.0.1:34956","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-27T14:48:14.677425Z","caller":"traceutil/trace.go:171","msg":"trace[670532037] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"404.280461ms","start":"2026-02-27T14:48:14.273122Z","end":"2026-02-27T14:48:14.677402Z","steps":["trace[670532037] 'read index received' (duration: 352.813768ms)","trace[670532037] 'applied index is now lower than readState.Index' (duration: 51.465823ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:14.677533Z","caller":"traceutil/trace.go:171","msg":"trace[634899442] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"409.569608ms","start":"2026-02-27T14:48:14.267931Z","end":"2026-02-27T14:48:14.6775Z","steps":["trace[634899442] 'process raft request' (duration: 409.357283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:14.677644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.496386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T14:48:14.677684Z","caller":"traceutil/trace.go:171","msg":"trace[1857687426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"404.560278ms","start":"2026-02-27T14:48:14.273111Z","end":"2026-02-27T14:48:14.677671Z","steps":["trace[1857687426] 'agreement among raft nodes before linearized reading' (duration: 404.420475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:14.677696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:14.267924Z","time spent":"409.678931ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:48:14.677717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:14.273106Z","time spent":"404.603498ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T14:48:15.0851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.86516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:48:15.085219Z","caller":"traceutil/trace.go:171","msg":"trace[2101984046] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"402.792326ms","start":"2026-02-27T14:48:14.682406Z","end":"2026-02-27T14:48:15.085198Z","steps":["trace[2101984046] 'process raft request' (duration: 82.712131ms)","trace[2101984046] 'compare' (duration: 319.715177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:48:15.085283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:14.682388Z","time spent":"402.866228ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:48:15.661601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":635461231594709845,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T14:48:15.808875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.043738145s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T14:48:16.327574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.366343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:48:16.327677Z","caller":"traceutil/trace.go:171","msg":"trace[1319126729] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"1.167005954s","start":"2026-02-27T14:48:15.160654Z","end":"2026-02-27T14:48:16.32766Z","steps":["trace[1319126729] 'read index received' (duration: 648.390815ms)","trace[1319126729] 'applied index is now lower than readState.Index' (duration: 518.614109ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:16.327767Z","caller":"traceutil/trace.go:171","msg":"trace[1289869712] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.635047141s","start":"2026-02-27T14:48:14.692708Z","end":"2026-02-27T14:48:16.327755Z","steps":["trace[1289869712] 'process raft request' (duration: 1.116412612s)","trace[1289869712] 'compare' (duration: 518.24324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:48:16.327829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:14.692678Z","time spent":"1.635122933s","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:48:16.327999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.583869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T14:48:16.328056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167384592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:48:16.328106Z","caller":"traceutil/trace.go:171","msg":"trace[1284143256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"711.715742ms","start":"2026-02-27T14:48:15.616356Z","end":"2026-02-27T14:48:16.328072Z","steps":["trace[1284143256] 'agreement among raft nodes before linearized reading' (duration: 711.440066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:16.328157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:15.616341Z","time spent":"711.805964ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T14:48:16.328158Z","caller":"traceutil/trace.go:171","msg":"trace[985994265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"1.167531356s","start":"2026-02-27T14:48:15.160592Z","end":"2026-02-27T14:48:16.328124Z","steps":["trace[985994265] 'agreement among raft nodes before linearized reading' (duration: 1.167379063s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:16.328202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:15.160571Z","time spent":"1.167623218s","remote":"127.0.0.1:34722","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-27T14:48:17.208224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.814403ms","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-27T14:48:17.20829Z","caller":"traceutil/trace.go:171","msg":"trace[876368862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"526.924926ms","start":"2026-02-27T14:48:16.681349Z","end":"2026-02-27T14:48:17.208274Z","steps":["trace[876368862] 'range keys from in-memory index tree' (duration: 526.733781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:17.208318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:16.681335Z","time spent":"526.977856ms","remote":"127.0.0.1:34956","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-27T14:48:17.208335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.691506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T14:48:17.208411Z","caller":"traceutil/trace.go:171","msg":"trace[2014281036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"118.809919ms","start":"2026-02-27T14:48:17.089585Z","end":"2026-02-27T14:48:17.208395Z","steps":["trace[2014281036] 'range keys from in-memory index tree' (duration: 118.549963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:17.208525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.403135ms","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-27T14:48:17.208538Z","caller":"traceutil/trace.go:171","msg":"trace[253624386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"577.461926ms","start":"2026-02-27T14:48:16.631072Z","end":"2026-02-27T14:48:17.208534Z","steps":["trace[253624386] 'range keys from in-memory index tree' (duration: 577.286033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:17.208553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:16.631048Z","time spent":"577.500698ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T14:48:17.43452Z","caller":"traceutil/trace.go:171","msg":"trace[813871018] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"223.236348ms","start":"2026-02-27T14:48:17.211254Z","end":"2026-02-27T14:48:17.43449Z","steps":["trace[813871018] 'process raft request' (duration: 223.176107ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:17.434872Z","caller":"traceutil/trace.go:171","msg":"trace[1372974129] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"223.78381ms","start":"2026-02-27T14:48:17.211061Z","end":"2026-02-27T14:48:17.434845Z","steps":["trace[1372974129] 'process raft request' (duration: 125.017389ms)","trace[1372974129] 'compare' (duration: 98.199288ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:48:18.707094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.053279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.225\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T14:48:18.707156Z","caller":"traceutil/trace.go:171","msg":"trace[572159795] range","detail":"{range_begin:/registry/masterleases/199.204.45.225; range_end:; response_count:1; response_revision:499; }","duration":"775.233144ms","start":"2026-02-27T14:48:17.931906Z","end":"2026-02-27T14:48:18.707139Z","steps":["trace[572159795] 'range keys from in-memory index tree' (duration: 774.948217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:18.707185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:17.931884Z","time spent":"775.293614ms","remote":"127.0.0.1:34748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.225\" "} {"level":"info","ts":"2026-02-27T14:48:18.707202Z","caller":"traceutil/trace.go:171","msg":"trace[1287513776] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"544.57988ms","start":"2026-02-27T14:48:18.162603Z","end":"2026-02-27T14:48:18.707183Z","steps":["trace[1287513776] 'read index received' (duration: 499.772517ms)","trace[1287513776] 'applied index is now lower than readState.Index' (duration: 44.806123ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:18.707363Z","caller":"traceutil/trace.go:171","msg":"trace[2080297800] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"682.747433ms","start":"2026-02-27T14:48:18.024603Z","end":"2026-02-27T14:48:18.70735Z","steps":["trace[2080297800] 'process raft request' (duration: 637.738036ms)","trace[2080297800] 'compare' (duration: 44.387503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:48:18.707456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:18.024579Z","time spent":"682.813264ms","remote":"127.0.0.1:34956","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-27T14:48:18.707467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.292401ms","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-27T14:48:18.70749Z","caller":"traceutil/trace.go:171","msg":"trace[807382124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"287.360132ms","start":"2026-02-27T14:48:18.420124Z","end":"2026-02-27T14:48:18.707484Z","steps":["trace[807382124] 'agreement among raft nodes before linearized reading' (duration: 287.27067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:18.707543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.917788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:48:18.70762Z","caller":"traceutil/trace.go:171","msg":"trace[608784684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"545.040521ms","start":"2026-02-27T14:48:18.162561Z","end":"2026-02-27T14:48:18.707601Z","steps":["trace[608784684] 'agreement among raft nodes before linearized reading' (duration: 544.919038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:18.707663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:18.162537Z","time spent":"545.117872ms","remote":"127.0.0.1:34722","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-27T14:48:22.131808Z","caller":"traceutil/trace.go:171","msg":"trace[419473085] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.661253ms","start":"2026-02-27T14:48:22.023121Z","end":"2026-02-27T14:48:22.131782Z","steps":["trace[419473085] 'process raft request' (duration: 108.52514ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:30.571661Z","caller":"traceutil/trace.go:171","msg":"trace[1961341594] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"215.92021ms","start":"2026-02-27T14:48:30.355712Z","end":"2026-02-27T14:48:30.571632Z","steps":["trace[1961341594] 'process raft request' (duration: 158.038308ms)","trace[1961341594] 'compare' (duration: 57.733088ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:30.656845Z","caller":"traceutil/trace.go:171","msg":"trace[1621017070] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"247.847093ms","start":"2026-02-27T14:48:30.408974Z","end":"2026-02-27T14:48:30.656821Z","steps":["trace[1621017070] 'process raft request' (duration: 247.808292ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:30.657068Z","caller":"traceutil/trace.go:171","msg":"trace[2025585975] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"299.302621ms","start":"2026-02-27T14:48:30.357753Z","end":"2026-02-27T14:48:30.657056Z","steps":["trace[2025585975] 'process raft request' (duration: 298.905292ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:36.966442Z","caller":"traceutil/trace.go:171","msg":"trace[1291032284] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"129.832028ms","start":"2026-02-27T14:48:36.836584Z","end":"2026-02-27T14:48:36.966416Z","steps":["trace[1291032284] 'read index received' (duration: 129.660984ms)","trace[1291032284] 'applied index is now lower than readState.Index' (duration: 170.094µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:36.966538Z","caller":"traceutil/trace.go:171","msg":"trace[82600111] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"175.336082ms","start":"2026-02-27T14:48:36.791144Z","end":"2026-02-27T14:48:36.96648Z","steps":["trace[82600111] 'process raft request' (duration: 175.123208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:36.966649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.045012ms","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-27T14:48:36.966693Z","caller":"traceutil/trace.go:171","msg":"trace[1832309984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"130.150375ms","start":"2026-02-27T14:48:36.836531Z","end":"2026-02-27T14:48:36.966681Z","steps":["trace[1832309984] 'agreement among raft nodes before linearized reading' (duration: 129.987001ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:39.955842Z","caller":"traceutil/trace.go:171","msg":"trace[1999458976] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"451.389225ms","start":"2026-02-27T14:48:39.504421Z","end":"2026-02-27T14:48:39.95581Z","steps":["trace[1999458976] 'read index received' (duration: 451.15449ms)","trace[1999458976] 'applied index is now lower than readState.Index' (duration: 233.525µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:39.955884Z","caller":"traceutil/trace.go:171","msg":"trace[2028815841] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"761.666849ms","start":"2026-02-27T14:48:39.194189Z","end":"2026-02-27T14:48:39.955856Z","steps":["trace[2028815841] 'process raft request' (duration: 761.428214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:39.956034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:39.194171Z","time spent":"761.782362ms","remote":"127.0.0.1:34956","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-27T14:48:39.95607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.674667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T14:48:39.956083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.63084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T14:48:39.956122Z","caller":"traceutil/trace.go:171","msg":"trace[658859570] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:690; }","duration":"385.746289ms","start":"2026-02-27T14:48:39.570357Z","end":"2026-02-27T14:48:39.956104Z","steps":["trace[658859570] 'agreement among raft nodes before linearized reading' (duration: 385.646297ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:39.956159Z","caller":"traceutil/trace.go:171","msg":"trace[64234402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"451.754262ms","start":"2026-02-27T14:48:39.504385Z","end":"2026-02-27T14:48:39.956139Z","steps":["trace[64234402] 'agreement among raft nodes before linearized reading' (duration: 451.526127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:39.956174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:39.570299Z","time spent":"385.862753ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-27T14:48:39.956202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:39.50437Z","time spent":"451.823396ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T14:48:39.956226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.549733ms","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-27T14:48:39.956254Z","caller":"traceutil/trace.go:171","msg":"trace[77023251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"375.594504ms","start":"2026-02-27T14:48:39.580653Z","end":"2026-02-27T14:48:39.956248Z","steps":["trace[77023251] 'agreement among raft nodes before linearized reading' (duration: 375.487701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:39.956279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:39.580644Z","time spent":"375.630375ms","remote":"127.0.0.1:34956","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-27T14:48:39.956387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.787994ms","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-27T14:48:39.956403Z","caller":"traceutil/trace.go:171","msg":"trace[510557243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"382.830405ms","start":"2026-02-27T14:48:39.573567Z","end":"2026-02-27T14:48:39.956397Z","steps":["trace[510557243] 'agreement among raft nodes before linearized reading' (duration: 382.779104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:48:39.956418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:48:39.573557Z","time spent":"382.858146ms","remote":"127.0.0.1:34956","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-27T14:48:40.218902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.216734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:48:40.219208Z","caller":"traceutil/trace.go:171","msg":"trace[587168416] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"258.796875ms","start":"2026-02-27T14:48:39.960383Z","end":"2026-02-27T14:48:40.21918Z","steps":["trace[587168416] 'process raft request' (duration: 60.166072ms)","trace[587168416] 'compare' (duration: 197.914868ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:48:40.219263Z","caller":"traceutil/trace.go:171","msg":"trace[763626470] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"258.577661ms","start":"2026-02-27T14:48:39.960665Z","end":"2026-02-27T14:48:40.219242Z","steps":["trace[763626470] 'process raft request' (duration: 258.493249ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:40.219256Z","caller":"traceutil/trace.go:171","msg":"trace[1461412423] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"258.563031ms","start":"2026-02-27T14:48:39.960668Z","end":"2026-02-27T14:48:40.219231Z","steps":["trace[1461412423] 'process raft request' (duration: 258.381297ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:06.634052Z","caller":"traceutil/trace.go:171","msg":"trace[716522081] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"293.755877ms","start":"2026-02-27T14:49:06.340255Z","end":"2026-02-27T14:49:06.634011Z","steps":["trace[716522081] 'process raft request' (duration: 293.524952ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:06.744144Z","caller":"traceutil/trace.go:171","msg":"trace[1811642663] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"394.23446ms","start":"2026-02-27T14:49:06.349883Z","end":"2026-02-27T14:49:06.744118Z","steps":["trace[1811642663] 'read index received' (duration: 283.850717ms)","trace[1811642663] 'applied index is now lower than readState.Index' (duration: 110.382563ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:06.744296Z","caller":"traceutil/trace.go:171","msg":"trace[8262810] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"403.896384ms","start":"2026-02-27T14:49:06.340365Z","end":"2026-02-27T14:49:06.744262Z","steps":["trace[8262810] 'process raft request' (duration: 389.327169ms)","trace[8262810] 'compare' (duration: 14.31928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:06.744399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.488486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T14:49:06.744439Z","caller":"traceutil/trace.go:171","msg":"trace[1539363506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"394.574577ms","start":"2026-02-27T14:49:06.349852Z","end":"2026-02-27T14:49:06.744427Z","steps":["trace[1539363506] 'agreement among raft nodes before linearized reading' (duration: 394.364592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:06.744482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:06.349839Z","time spent":"394.635578ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T14:49:06.744736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:06.340353Z","time spent":"404.059999ms","remote":"127.0.0.1:34956","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-27T14:49:08.398096Z","caller":"traceutil/trace.go:171","msg":"trace[1469304190] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"253.435726ms","start":"2026-02-27T14:49:08.14463Z","end":"2026-02-27T14:49:08.398065Z","steps":["trace[1469304190] 'process raft request' (duration: 223.698923ms)","trace[1469304190] 'compare' (duration: 29.487038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:08.398394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.816893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:49:08.398527Z","caller":"traceutil/trace.go:171","msg":"trace[1283942751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"235.996237ms","start":"2026-02-27T14:49:08.162469Z","end":"2026-02-27T14:49:08.398465Z","steps":["trace[1283942751] 'agreement among raft nodes before linearized reading' (duration: 235.6694ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:08.398084Z","caller":"traceutil/trace.go:171","msg":"trace[589608900] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"235.512386ms","start":"2026-02-27T14:49:08.16252Z","end":"2026-02-27T14:49:08.398033Z","steps":["trace[589608900] 'read index received' (duration: 205.746583ms)","trace[589608900] 'applied index is now lower than readState.Index' (duration: 29.764713ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:08.776931Z","caller":"traceutil/trace.go:171","msg":"trace[1488519567] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"130.833411ms","start":"2026-02-27T14:49:08.646073Z","end":"2026-02-27T14:49:08.776907Z","steps":["trace[1488519567] 'process raft request' (duration: 130.277247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:09.478234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.379492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:49:09.478336Z","caller":"traceutil/trace.go:171","msg":"trace[522949390] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:779; }","duration":"282.555826ms","start":"2026-02-27T14:49:09.195763Z","end":"2026-02-27T14:49:09.478319Z","steps":["trace[522949390] 'count revisions from in-memory index tree' (duration: 282.274051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:09.478576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.271336ms","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-27T14:49:09.478651Z","caller":"traceutil/trace.go:171","msg":"trace[402516119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"194.412169ms","start":"2026-02-27T14:49:09.284222Z","end":"2026-02-27T14:49:09.478634Z","steps":["trace[402516119] 'range keys from in-memory index tree' (duration: 194.068432ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:09.665931Z","caller":"traceutil/trace.go:171","msg":"trace[1517218975] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"183.25781ms","start":"2026-02-27T14:49:09.482639Z","end":"2026-02-27T14:49:09.665897Z","steps":["trace[1517218975] 'process raft request' (duration: 182.935043ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:10.571252Z","caller":"traceutil/trace.go:171","msg":"trace[2107569325] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"409.567321ms","start":"2026-02-27T14:49:10.161662Z","end":"2026-02-27T14:49:10.571229Z","steps":["trace[2107569325] 'read index received' (duration: 409.426128ms)","trace[2107569325] 'applied index is now lower than readState.Index' (duration: 140.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:10.57128Z","caller":"traceutil/trace.go:171","msg":"trace[1223230299] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"424.209618ms","start":"2026-02-27T14:49:10.147051Z","end":"2026-02-27T14:49:10.571261Z","steps":["trace[1223230299] 'process raft request' (duration: 424.048664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:10.571359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.680434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T14:49:10.571401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:10.147023Z","time spent":"424.30781ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:49:10.571467Z","caller":"traceutil/trace.go:171","msg":"trace[1007658087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"409.722795ms","start":"2026-02-27T14:49:10.161658Z","end":"2026-02-27T14:49:10.57138Z","steps":["trace[1007658087] 'agreement among raft nodes before linearized reading' (duration: 409.659584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:10.571506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:10.161617Z","time spent":"409.881328ms","remote":"127.0.0.1:34722","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-27T14:49:21.052785Z","caller":"traceutil/trace.go:171","msg":"trace[1767168386] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"146.627074ms","start":"2026-02-27T14:49:20.906142Z","end":"2026-02-27T14:49:21.052769Z","steps":["trace[1767168386] 'process raft request' (duration: 146.533132ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:21.055719Z","caller":"traceutil/trace.go:171","msg":"trace[452744126] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"148.231899ms","start":"2026-02-27T14:49:20.907463Z","end":"2026-02-27T14:49:21.055694Z","steps":["trace[452744126] 'process raft request' (duration: 148.081325ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:27.266215Z","caller":"traceutil/trace.go:171","msg":"trace[1021453895] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"185.536779ms","start":"2026-02-27T14:49:27.080648Z","end":"2026-02-27T14:49:27.266185Z","steps":["trace[1021453895] 'read index received' (duration: 185.345605ms)","trace[1021453895] 'applied index is now lower than readState.Index' (duration: 190.044µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:27.26656Z","caller":"traceutil/trace.go:171","msg":"trace[2003347322] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"338.816119ms","start":"2026-02-27T14:49:26.927733Z","end":"2026-02-27T14:49:27.266549Z","steps":["trace[2003347322] 'process raft request' (duration: 338.313068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:27.266667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:26.927711Z","time spent":"338.886031ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:49:27.266891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.247864ms","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-27T14:49:27.266929Z","caller":"traceutil/trace.go:171","msg":"trace[853196202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"186.322166ms","start":"2026-02-27T14:49:27.080597Z","end":"2026-02-27T14:49:27.266919Z","steps":["trace[853196202] 'agreement among raft nodes before linearized reading' (duration: 186.210753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:27.267216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.363718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T14:49:27.267299Z","caller":"traceutil/trace.go:171","msg":"trace[1760941920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"182.501311ms","start":"2026-02-27T14:49:27.084779Z","end":"2026-02-27T14:49:27.267281Z","steps":["trace[1760941920] 'agreement among raft nodes before linearized reading' (duration: 182.304087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:27.492345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.715789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:49:27.492465Z","caller":"traceutil/trace.go:171","msg":"trace[491417291] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"221.463068ms","start":"2026-02-27T14:49:27.270984Z","end":"2026-02-27T14:49:27.492447Z","steps":["trace[491417291] 'process raft request' (duration: 54.552214ms)","trace[491417291] 'compare' (duration: 166.613397ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:27.505602Z","caller":"traceutil/trace.go:171","msg":"trace[679761074] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"232.656446ms","start":"2026-02-27T14:49:27.27292Z","end":"2026-02-27T14:49:27.505577Z","steps":["trace[679761074] 'process raft request' (duration: 232.587115ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:27.50565Z","caller":"traceutil/trace.go:171","msg":"trace[1976144922] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"234.510508ms","start":"2026-02-27T14:49:27.271105Z","end":"2026-02-27T14:49:27.505615Z","steps":["trace[1976144922] 'process raft request' (duration: 234.293093ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:42.094825Z","caller":"traceutil/trace.go:171","msg":"trace[1932164705] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"114.728602ms","start":"2026-02-27T14:49:41.980075Z","end":"2026-02-27T14:49:42.094804Z","steps":["trace[1932164705] 'process raft request' (duration: 114.61485ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:42.280893Z","caller":"traceutil/trace.go:171","msg":"trace[1843290296] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"118.609939ms","start":"2026-02-27T14:49:42.162255Z","end":"2026-02-27T14:49:42.280865Z","steps":["trace[1843290296] 'read index received' (duration: 79.98481ms)","trace[1843290296] 'applied index is now lower than readState.Index' (duration: 38.624099ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:42.281116Z","caller":"traceutil/trace.go:171","msg":"trace[1458361377] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"295.185169ms","start":"2026-02-27T14:49:41.985899Z","end":"2026-02-27T14:49:42.281084Z","steps":["trace[1458361377] 'process raft request' (duration: 294.904402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:42.281137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.855695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:49:42.281208Z","caller":"traceutil/trace.go:171","msg":"trace[625126707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"118.985318ms","start":"2026-02-27T14:49:42.162209Z","end":"2026-02-27T14:49:42.281194Z","steps":["trace[625126707] 'agreement among raft nodes before linearized reading' (duration: 118.763232ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:42.281184Z","caller":"traceutil/trace.go:171","msg":"trace[973890914] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"300.426976ms","start":"2026-02-27T14:49:41.980735Z","end":"2026-02-27T14:49:42.281162Z","steps":["trace[973890914] 'process raft request' (duration: 261.587291ms)","trace[973890914] 'compare' (duration: 38.254501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:42.281349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:41.980718Z","time spent":"300.557578ms","remote":"127.0.0.1:34960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:49:45.220673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054826962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:49:45.220754Z","caller":"traceutil/trace.go:171","msg":"trace[1525243349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"1.054949144s","start":"2026-02-27T14:49:44.165787Z","end":"2026-02-27T14:49:45.220736Z","steps":["trace[1525243349] 'range keys from in-memory index tree' (duration: 1.05474866s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:45.220804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:44.165767Z","time spent":"1.055028116s","remote":"127.0.0.1:34722","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-27T14:49:45.220826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.904642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T14:49:45.220915Z","caller":"traceutil/trace.go:171","msg":"trace[1919606511] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:954; }","duration":"308.050485ms","start":"2026-02-27T14:49:44.912841Z","end":"2026-02-27T14:49:45.220892Z","steps":["trace[1919606511] 'count revisions from in-memory index tree' (duration: 307.808729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:45.220961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:44.912822Z","time spent":"308.128356ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-27T14:49:45.750493Z","caller":"traceutil/trace.go:171","msg":"trace[2002264124] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"123.55115ms","start":"2026-02-27T14:49:45.626914Z","end":"2026-02-27T14:49:45.750465Z","steps":["trace[2002264124] 'process raft request' (duration: 123.213092ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:45.859772Z","caller":"traceutil/trace.go:171","msg":"trace[749066593] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"105.187341ms","start":"2026-02-27T14:49:45.754558Z","end":"2026-02-27T14:49:45.859745Z","steps":["trace[749066593] 'process raft request' (duration: 49.778518ms)","trace[749066593] 'compare' (duration: 55.179028ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:46.503597Z","caller":"traceutil/trace.go:171","msg":"trace[1132710769] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"472.588556ms","start":"2026-02-27T14:49:46.030975Z","end":"2026-02-27T14:49:46.503563Z","steps":["trace[1132710769] 'process raft request' (duration: 375.24894ms)","trace[1132710769] 'compare' (duration: 97.124371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:46.503742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:46.03096Z","time spent":"472.706248ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:49:46.828128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.120231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:49:46.828227Z","caller":"traceutil/trace.go:171","msg":"trace[1688331825] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:966; }","duration":"208.259685ms","start":"2026-02-27T14:49:46.619945Z","end":"2026-02-27T14:49:46.828205Z","steps":["trace[1688331825] 'count revisions from in-memory index tree' (duration: 208.04585ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:48.144868Z","caller":"traceutil/trace.go:171","msg":"trace[217198492] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"190.046129ms","start":"2026-02-27T14:49:47.954786Z","end":"2026-02-27T14:49:48.144832Z","steps":["trace[217198492] 'process raft request' (duration: 189.921916ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:48.145216Z","caller":"traceutil/trace.go:171","msg":"trace[1197737457] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"191.513052ms","start":"2026-02-27T14:49:47.953395Z","end":"2026-02-27T14:49:48.144908Z","steps":["trace[1197737457] 'process raft request' (duration: 176.209301ms)","trace[1197737457] 'compare' (duration: 14.735458ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:48.612958Z","caller":"traceutil/trace.go:171","msg":"trace[111310296] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"452.519039ms","start":"2026-02-27T14:49:48.160408Z","end":"2026-02-27T14:49:48.612927Z","steps":["trace[111310296] 'read index received' (duration: 384.6929ms)","trace[111310296] 'applied index is now lower than readState.Index' (duration: 67.824739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:48.613234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.781115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:49:48.613326Z","caller":"traceutil/trace.go:171","msg":"trace[112454366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"452.939058ms","start":"2026-02-27T14:49:48.160365Z","end":"2026-02-27T14:49:48.613304Z","steps":["trace[112454366] 'agreement among raft nodes before linearized reading' (duration: 452.713163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:48.613379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:48.160348Z","time spent":"453.022651ms","remote":"127.0.0.1:34722","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-27T14:49:48.613061Z","caller":"traceutil/trace.go:171","msg":"trace[857823240] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"616.618881ms","start":"2026-02-27T14:49:47.996374Z","end":"2026-02-27T14:49:48.612993Z","steps":["trace[857823240] 'process raft request' (duration: 548.760691ms)","trace[857823240] 'compare' (duration: 67.640855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:48.613665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:47.996357Z","time spent":"617.219203ms","remote":"127.0.0.1:34748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:49:49.174462Z","caller":"traceutil/trace.go:171","msg":"trace[905535861] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"157.606327ms","start":"2026-02-27T14:49:49.016832Z","end":"2026-02-27T14:49:49.174439Z","steps":["trace[905535861] 'process raft request' (duration: 157.471514ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:49.595275Z","caller":"traceutil/trace.go:171","msg":"trace[713065650] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"170.920823ms","start":"2026-02-27T14:49:49.424299Z","end":"2026-02-27T14:49:49.59522Z","steps":["trace[713065650] 'read index received' (duration: 151.90365ms)","trace[713065650] 'applied index is now lower than readState.Index' (duration: 19.015483ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:49.595452Z","caller":"traceutil/trace.go:171","msg":"trace[1333921336] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"380.022596ms","start":"2026-02-27T14:49:49.215386Z","end":"2026-02-27T14:49:49.595408Z","steps":["trace[1333921336] 'process raft request' (duration: 360.742108ms)","trace[1333921336] 'compare' (duration: 18.758877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:49.595579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.254821ms","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-27T14:49:49.595624Z","caller":"traceutil/trace.go:171","msg":"trace[813174305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"171.343934ms","start":"2026-02-27T14:49:49.424267Z","end":"2026-02-27T14:49:49.59561Z","steps":["trace[813174305] 'agreement among raft nodes before linearized reading' (duration: 171.096077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:49.59562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:49.21537Z","time spent":"380.16834ms","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T14:49:50.063439Z","caller":"traceutil/trace.go:171","msg":"trace[642236425] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"222.995612ms","start":"2026-02-27T14:49:49.840399Z","end":"2026-02-27T14:49:50.063394Z","steps":["trace[642236425] 'process raft request' (duration: 186.48057ms)","trace[642236425] 'compare' (duration: 36.366318ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:51.909085Z","caller":"traceutil/trace.go:171","msg":"trace[1093461512] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"230.09072ms","start":"2026-02-27T14:49:51.678923Z","end":"2026-02-27T14:49:51.909013Z","steps":["trace[1093461512] 'read index received' (duration: 229.884126ms)","trace[1093461512] 'applied index is now lower than readState.Index' (duration: 205.064µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:49:51.909322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.384836ms","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-27T14:49:51.909458Z","caller":"traceutil/trace.go:171","msg":"trace[915529834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"230.594171ms","start":"2026-02-27T14:49:51.678849Z","end":"2026-02-27T14:49:51.909443Z","steps":["trace[915529834] 'agreement among raft nodes before linearized reading' (duration: 230.335915ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:51.909524Z","caller":"traceutil/trace.go:171","msg":"trace[1471509396] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"398.10385ms","start":"2026-02-27T14:49:51.511389Z","end":"2026-02-27T14:49:51.909493Z","steps":["trace[1471509396] 'process raft request' (duration: 397.403143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:49:51.909815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:49:51.511374Z","time spent":"398.298913ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:49:51.910223Z","caller":"traceutil/trace.go:171","msg":"trace[40056230] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"192.956724ms","start":"2026-02-27T14:49:51.717238Z","end":"2026-02-27T14:49:51.910195Z","steps":["trace[40056230] 'process raft request' (duration: 192.819961ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:52.469628Z","caller":"traceutil/trace.go:171","msg":"trace[1174792097] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"133.231194ms","start":"2026-02-27T14:49:52.336374Z","end":"2026-02-27T14:49:52.469605Z","steps":["trace[1174792097] 'process raft request' (duration: 133.125541ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:42.27229Z","caller":"traceutil/trace.go:171","msg":"trace[416354178] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1265; }","duration":"111.961256ms","start":"2026-02-27T14:50:42.160301Z","end":"2026-02-27T14:50:42.272262Z","steps":["trace[416354178] 'read index received' (duration: 80.452108ms)","trace[416354178] 'applied index is now lower than readState.Index' (duration: 31.508048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:50:42.272557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.238282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:50:42.272644Z","caller":"traceutil/trace.go:171","msg":"trace[1676778138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"112.360794ms","start":"2026-02-27T14:50:42.160272Z","end":"2026-02-27T14:50:42.272633Z","steps":["trace[1676778138] 'agreement among raft nodes before linearized reading' (duration: 112.234352ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:42.273277Z","caller":"traceutil/trace.go:171","msg":"trace[952464618] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"117.821865ms","start":"2026-02-27T14:50:42.154683Z","end":"2026-02-27T14:50:42.272505Z","steps":["trace[952464618] 'process raft request' (duration: 117.430177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:44.918408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.664327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:51:44.918532Z","caller":"traceutil/trace.go:171","msg":"trace[677359070] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1467; }","duration":"159.860271ms","start":"2026-02-27T14:51:44.758648Z","end":"2026-02-27T14:51:44.918508Z","steps":["trace[677359070] 'count revisions from in-memory index tree' (duration: 159.573665ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:53:07.218789Z","caller":"traceutil/trace.go:171","msg":"trace[277537717] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"215.481524ms","start":"2026-02-27T14:53:07.003273Z","end":"2026-02-27T14:53:07.218754Z","steps":["trace[277537717] 'process raft request' (duration: 214.941292ms)"],"step_count":1}