{"level":"warn","ts":"2026-02-17T23:10:39.862018Z","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-17T23:10:39.862103Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-17T23:10:39.862171Z","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-17T23:10:39.862182Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-17T23:10:39.862204Z","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-17T23:10:39.862844Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-02-17T23:10:39.86291Z","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://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-17T23:10:39.918028Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"54.88596ms"} {"level":"info","ts":"2026-02-17T23:10:39.968894Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-02-17T23:10:39.968966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T23:10:39.96905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-02-17T23:10:39.969065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T23:10:39.969071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-02-17T23:10:39.969105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-02-17T23:10:39.977814Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T23:10:39.981409Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T23:10:39.984089Z","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-17T23:10:39.986956Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T23:10:39.987104Z","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-17T23:10:39.987245Z","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-17T23:10:39.987307Z","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-17T23:10:39.991518Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T23:10:39.995378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-02-17T23:10:39.995507Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-17T23:10:39.996515Z","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-17T23:10:39.996774Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-17T23:10:39.9968Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-17T23:10:39.997155Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T23:10:39.997244Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T23:10:40.669961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T23:10:40.670066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T23:10:40.6701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-02-17T23:10:40.670139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-02-17T23:10:40.670164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-17T23:10:40.670186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-02-17T23:10:40.670207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-17T23:10:40.672225Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:40.673041Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T23:10:40.673133Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T23:10:40.675166Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-02-17T23:10:40.675267Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:40.675401Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:40.675437Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:40.675738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T23:10:40.677196Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T23:10:40.688331Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T23:10:40.688461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T23:10:43.147009Z","caller":"traceutil/trace.go:171","msg":"trace[312914290] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"148.71433ms","start":"2026-02-17T23:10:42.998274Z","end":"2026-02-17T23:10:43.146988Z","steps":["trace[312914290] 'process raft request' (duration: 148.530425ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:57.500084Z","caller":"traceutil/trace.go:171","msg":"trace[927930510] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:393; }","duration":"137.388797ms","start":"2026-02-17T23:10:57.362658Z","end":"2026-02-17T23:10:57.500046Z","steps":["trace[927930510] 'read index received' (duration: 44.389903ms)","trace[927930510] 'applied index is now lower than readState.Index' (duration: 92.998154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:57.500263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.58907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:57.500297Z","caller":"traceutil/trace.go:171","msg":"trace[1733754520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"137.634301ms","start":"2026-02-17T23:10:57.362654Z","end":"2026-02-17T23:10:57.500288Z","steps":["trace[1733754520] 'agreement among raft nodes before linearized reading' (duration: 137.479848ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:57.500566Z","caller":"traceutil/trace.go:171","msg":"trace[1252366215] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"180.764049ms","start":"2026-02-17T23:10:57.319793Z","end":"2026-02-17T23:10:57.500557Z","steps":["trace[1252366215] 'process raft request' (duration: 180.183918ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:02.550476Z","caller":"traceutil/trace.go:171","msg":"trace[1930630205] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"173.662067ms","start":"2026-02-17T23:11:02.376795Z","end":"2026-02-17T23:11:02.550457Z","steps":["trace[1930630205] 'process raft request' (duration: 173.522765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:09.67234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.459349ms","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-17T23:11:09.672426Z","caller":"traceutil/trace.go:171","msg":"trace[1778135728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:440; }","duration":"449.612982ms","start":"2026-02-17T23:11:09.222796Z","end":"2026-02-17T23:11:09.672409Z","steps":["trace[1778135728] 'range keys from in-memory index tree' (duration: 449.367427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:09.672488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:09.222781Z","time spent":"449.697193ms","remote":"127.0.0.1:37464","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-17T23:11:13.5314Z","caller":"traceutil/trace.go:171","msg":"trace[1547406252] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"147.013878ms","start":"2026-02-17T23:11:13.384368Z","end":"2026-02-17T23:11:13.531382Z","steps":["trace[1547406252] 'process raft request' (duration: 146.901366ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:14.260031Z","caller":"traceutil/trace.go:171","msg":"trace[937031502] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"535.87086ms","start":"2026-02-17T23:11:13.724142Z","end":"2026-02-17T23:11:14.260013Z","steps":["trace[937031502] 'process raft request' (duration: 535.776518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:14.260432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:13.724126Z","time spent":"535.979031ms","remote":"127.0.0.1:37464","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-17T23:11:16.457053Z","caller":"traceutil/trace.go:171","msg":"trace[661255255] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"189.573495ms","start":"2026-02-17T23:11:16.267465Z","end":"2026-02-17T23:11:16.457038Z","steps":["trace[661255255] 'process raft request' (duration: 189.226568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:16.778677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.940745ms","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-17T23:11:16.778726Z","caller":"traceutil/trace.go:171","msg":"trace[1200465743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"180.017946ms","start":"2026-02-17T23:11:16.598698Z","end":"2026-02-17T23:11:16.778716Z","steps":["trace[1200465743] 'range keys from in-memory index tree' (duration: 179.886343ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:17.248817Z","caller":"traceutil/trace.go:171","msg":"trace[1515809189] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"193.729443ms","start":"2026-02-17T23:11:17.055074Z","end":"2026-02-17T23:11:17.248803Z","steps":["trace[1515809189] 'read index received' (duration: 193.57386ms)","trace[1515809189] 'applied index is now lower than readState.Index' (duration: 154.953µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:17.248894Z","caller":"traceutil/trace.go:171","msg":"trace[639942360] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"467.841484ms","start":"2026-02-17T23:11:16.781047Z","end":"2026-02-17T23:11:17.248888Z","steps":["trace[639942360] 'process raft request' (duration: 467.654941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:17.248967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:16.781035Z","time spent":"467.876285ms","remote":"127.0.0.1:37464","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-17T23:11:17.248991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.422139ms","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-17T23:11:17.249042Z","caller":"traceutil/trace.go:171","msg":"trace[852330277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"191.498801ms","start":"2026-02-17T23:11:17.057531Z","end":"2026-02-17T23:11:17.249029Z","steps":["trace[852330277] 'agreement among raft nodes before linearized reading' (duration: 191.41446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:17.24914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.066569ms","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-17T23:11:17.249175Z","caller":"traceutil/trace.go:171","msg":"trace[513694519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"194.11764ms","start":"2026-02-17T23:11:17.055035Z","end":"2026-02-17T23:11:17.249152Z","steps":["trace[513694519] 'agreement among raft nodes before linearized reading' (duration: 194.08639ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:17.506546Z","caller":"traceutil/trace.go:171","msg":"trace[1089822018] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"247.438521ms","start":"2026-02-17T23:11:17.259086Z","end":"2026-02-17T23:11:17.506524Z","steps":["trace[1089822018] 'process raft request' (duration: 247.39201ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:17.506847Z","caller":"traceutil/trace.go:171","msg":"trace[1777610046] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"253.692518ms","start":"2026-02-17T23:11:17.253142Z","end":"2026-02-17T23:11:17.506834Z","steps":["trace[1777610046] 'process raft request' (duration: 228.010496ms)","trace[1777610046] 'compare' (duration: 24.976189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:22.935207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.699283ms","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-17T23:11:22.935307Z","caller":"traceutil/trace.go:171","msg":"trace[1918066802] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:506; }","duration":"843.810626ms","start":"2026-02-17T23:11:22.091483Z","end":"2026-02-17T23:11:22.935293Z","steps":["trace[1918066802] 'range keys from in-memory index tree' (duration: 843.685963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:22.935521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.279747ms","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-17T23:11:22.935544Z","caller":"traceutil/trace.go:171","msg":"trace[1719401087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"456.303707ms","start":"2026-02-17T23:11:22.479233Z","end":"2026-02-17T23:11:22.935536Z","steps":["trace[1719401087] 'range keys from in-memory index tree' (duration: 456.216326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:22.935568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:22.479191Z","time spent":"456.370289ms","remote":"127.0.0.1:37464","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-17T23:11:22.935809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.086681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vcps4\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T23:11:22.935875Z","caller":"traceutil/trace.go:171","msg":"trace[1557011275] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vcps4; range_end:; response_count:1; response_revision:506; }","duration":"595.191512ms","start":"2026-02-17T23:11:22.340665Z","end":"2026-02-17T23:11:22.935856Z","steps":["trace[1557011275] 'range keys from in-memory index tree' (duration: 594.935398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:22.935913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:22.340648Z","time spent":"595.255604ms","remote":"127.0.0.1:37394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-vcps4\" "} {"level":"info","ts":"2026-02-17T23:11:23.161821Z","caller":"traceutil/trace.go:171","msg":"trace[46735287] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"221.860492ms","start":"2026-02-17T23:11:22.939938Z","end":"2026-02-17T23:11:23.161798Z","steps":["trace[46735287] 'process raft request' (duration: 221.73652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.783533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297723246,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:11:23.857213Z","caller":"traceutil/trace.go:171","msg":"trace[385748144] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"907.097842ms","start":"2026-02-17T23:11:22.95009Z","end":"2026-02-17T23:11:23.857188Z","steps":["trace[385748144] 'process raft request' (duration: 906.941399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.857372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:22.950068Z","time spent":"907.212533ms","remote":"127.0.0.1:37394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:23.993604Z","caller":"traceutil/trace.go:171","msg":"trace[711615969] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"710.239539ms","start":"2026-02-17T23:11:23.283342Z","end":"2026-02-17T23:11:23.993582Z","steps":["trace[711615969] 'read index received' (duration: 574.656016ms)","trace[711615969] 'applied index is now lower than readState.Index' (duration: 135.582603ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:23.993744Z","caller":"traceutil/trace.go:171","msg":"trace[1283613617] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"286.108797ms","start":"2026-02-17T23:11:23.707624Z","end":"2026-02-17T23:11:23.993733Z","steps":["trace[1283613617] 'process raft request' (duration: 285.81658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.994852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.246277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:23.994906Z","caller":"traceutil/trace.go:171","msg":"trace[1771188237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"632.327729ms","start":"2026-02-17T23:11:23.362562Z","end":"2026-02-17T23:11:23.99489Z","steps":["trace[1771188237] 'agreement among raft nodes before linearized reading' (duration: 632.246377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.994946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.362545Z","time spent":"632.39033ms","remote":"127.0.0.1:37186","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-17T23:11:23.995181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.909982ms","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-17T23:11:23.99522Z","caller":"traceutil/trace.go:171","msg":"trace[2079180493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"405.963314ms","start":"2026-02-17T23:11:23.589245Z","end":"2026-02-17T23:11:23.995208Z","steps":["trace[2079180493] 'agreement among raft nodes before linearized reading' (duration: 405.890042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.995269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.589237Z","time spent":"406.022045ms","remote":"127.0.0.1:37464","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-17T23:11:23.995442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.846468ms","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-17T23:11:23.995605Z","caller":"traceutil/trace.go:171","msg":"trace[1258594216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"407.945499ms","start":"2026-02-17T23:11:23.587525Z","end":"2026-02-17T23:11:23.99547Z","steps":["trace[1258594216] 'agreement among raft nodes before linearized reading' (duration: 407.877248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.995655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.587491Z","time spent":"408.150194ms","remote":"127.0.0.1:37464","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-17T23:11:23.994494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.118436ms","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-17T23:11:23.995722Z","caller":"traceutil/trace.go:171","msg":"trace[2124813197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"712.42751ms","start":"2026-02-17T23:11:23.283279Z","end":"2026-02-17T23:11:23.995706Z","steps":["trace[2124813197] 'agreement among raft nodes before linearized reading' (duration: 711.127086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.995768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.283258Z","time spent":"712.494582ms","remote":"127.0.0.1:37464","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-17T23:11:26.20755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.243818ms","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-17T23:11:26.207612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.573058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T23:11:26.207661Z","caller":"traceutil/trace.go:171","msg":"trace[1995563226] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:524; }","duration":"492.644369ms","start":"2026-02-17T23:11:25.715004Z","end":"2026-02-17T23:11:26.207648Z","steps":["trace[1995563226] 'range keys from in-memory index tree' (duration: 492.518227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:26.207666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.397389ms","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-17T23:11:26.207695Z","caller":"traceutil/trace.go:171","msg":"trace[2131734491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"192.47443ms","start":"2026-02-17T23:11:26.015212Z","end":"2026-02-17T23:11:26.207687Z","steps":["trace[2131734491] 'range keys from in-memory index tree' (duration: 192.339748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:26.207699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:25.714991Z","time spent":"492.69749ms","remote":"127.0.0.1:37302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-17T23:11:26.207885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.499551ms","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-17T23:11:26.20762Z","caller":"traceutil/trace.go:171","msg":"trace[188957501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"190.36428ms","start":"2026-02-17T23:11:26.017238Z","end":"2026-02-17T23:11:26.207603Z","steps":["trace[188957501] 'range keys from in-memory index tree' (duration: 190.121445ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:26.207913Z","caller":"traceutil/trace.go:171","msg":"trace[593483273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"191.543382ms","start":"2026-02-17T23:11:26.016361Z","end":"2026-02-17T23:11:26.207904Z","steps":["trace[593483273] 'range keys from in-memory index tree' (duration: 191.467201ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:26.441206Z","caller":"traceutil/trace.go:171","msg":"trace[1405845846] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"226.658231ms","start":"2026-02-17T23:11:26.21453Z","end":"2026-02-17T23:11:26.441188Z","steps":["trace[1405845846] 'process raft request' (duration: 226.559439ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:26.441798Z","caller":"traceutil/trace.go:171","msg":"trace[1806838792] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:545; }","duration":"225.324495ms","start":"2026-02-17T23:11:26.216462Z","end":"2026-02-17T23:11:26.441787Z","steps":["trace[1806838792] 'read index received' (duration: 225.291225ms)","trace[1806838792] 'applied index is now lower than readState.Index' (duration: 3.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:26.441901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.419587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:26.441931Z","caller":"traceutil/trace.go:171","msg":"trace[53930063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"225.466098ms","start":"2026-02-17T23:11:26.216457Z","end":"2026-02-17T23:11:26.441923Z","steps":["trace[53930063] 'agreement among raft nodes before linearized reading' (duration: 225.402227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:26.452508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.675409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:26.45346Z","caller":"traceutil/trace.go:171","msg":"trace[1402989964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:527; }","duration":"219.73832ms","start":"2026-02-17T23:11:26.2328Z","end":"2026-02-17T23:11:26.452538Z","steps":["trace[1402989964] 'agreement among raft nodes before linearized reading' (duration: 219.672969ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:26.453659Z","caller":"traceutil/trace.go:171","msg":"trace[1506764958] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"233.887326ms","start":"2026-02-17T23:11:26.219751Z","end":"2026-02-17T23:11:26.453639Z","steps":["trace[1506764958] 'process raft request' (duration: 232.647092ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:26.453906Z","caller":"traceutil/trace.go:171","msg":"trace[1253813306] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"232.917608ms","start":"2026-02-17T23:11:26.220974Z","end":"2026-02-17T23:11:26.453891Z","steps":["trace[1253813306] 'process raft request' (duration: 231.47281ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:27.41628Z","caller":"traceutil/trace.go:171","msg":"trace[2041490873] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"126.37117ms","start":"2026-02-17T23:11:27.289886Z","end":"2026-02-17T23:11:27.416258Z","steps":["trace[2041490873] 'process raft request' (duration: 121.469639ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:27.597544Z","caller":"traceutil/trace.go:171","msg":"trace[1681461933] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"105.514709ms","start":"2026-02-17T23:11:27.492017Z","end":"2026-02-17T23:11:27.597532Z","steps":["trace[1681461933] 'process raft request' (duration: 105.456618ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:27.653587Z","caller":"traceutil/trace.go:171","msg":"trace[87719868] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"147.236711ms","start":"2026-02-17T23:11:27.506338Z","end":"2026-02-17T23:11:27.653575Z","steps":["trace[87719868] 'process raft request' (duration: 147.20496ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:27.653722Z","caller":"traceutil/trace.go:171","msg":"trace[1564694500] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"152.689212ms","start":"2026-02-17T23:11:27.501027Z","end":"2026-02-17T23:11:27.653716Z","steps":["trace[1564694500] 'process raft request' (duration: 152.448698ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:32.066639Z","caller":"traceutil/trace.go:171","msg":"trace[126453280] transaction","detail":"{read_only:false; number_of_response:0; response_revision:612; }","duration":"111.363228ms","start":"2026-02-17T23:11:31.955259Z","end":"2026-02-17T23:11:32.066622Z","steps":["trace[126453280] 'process raft request' (duration: 107.510286ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:34.229861Z","caller":"traceutil/trace.go:171","msg":"trace[1065682644] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"159.999192ms","start":"2026-02-17T23:11:34.069846Z","end":"2026-02-17T23:11:34.229845Z","steps":["trace[1065682644] 'process raft request' (duration: 159.912129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:34.759328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.462496ms","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-17T23:11:34.759378Z","caller":"traceutil/trace.go:171","msg":"trace[1034254373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:626; }","duration":"115.538128ms","start":"2026-02-17T23:11:34.643827Z","end":"2026-02-17T23:11:34.759365Z","steps":["trace[1034254373] 'range keys from in-memory index tree' (duration: 115.327563ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:34.801377Z","caller":"traceutil/trace.go:171","msg":"trace[1816451909] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"125.090707ms","start":"2026-02-17T23:11:34.676268Z","end":"2026-02-17T23:11:34.801358Z","steps":["trace[1816451909] 'process raft request' (duration: 124.967444ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:35.009483Z","caller":"traceutil/trace.go:171","msg":"trace[830146549] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"163.238601ms","start":"2026-02-17T23:11:34.846229Z","end":"2026-02-17T23:11:35.009468Z","steps":["trace[830146549] 'process raft request' (duration: 163.15375ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:36.147113Z","caller":"traceutil/trace.go:171","msg":"trace[1205786310] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"102.835349ms","start":"2026-02-17T23:11:36.044262Z","end":"2026-02-17T23:11:36.147097Z","steps":["trace[1205786310] 'process raft request' (duration: 102.738788ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:37.281986Z","caller":"traceutil/trace.go:171","msg":"trace[1558770316] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"119.874568ms","start":"2026-02-17T23:11:37.162096Z","end":"2026-02-17T23:11:37.281971Z","steps":["trace[1558770316] 'process raft request' (duration: 119.387909ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:38.053655Z","caller":"traceutil/trace.go:171","msg":"trace[240883883] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"270.300479ms","start":"2026-02-17T23:11:37.78334Z","end":"2026-02-17T23:11:38.05364Z","steps":["trace[240883883] 'process raft request' (duration: 270.268708ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:38.053723Z","caller":"traceutil/trace.go:171","msg":"trace[1327952959] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"270.421702ms","start":"2026-02-17T23:11:37.783269Z","end":"2026-02-17T23:11:38.053691Z","steps":["trace[1327952959] 'process raft request' (duration: 270.165436ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:38.841944Z","caller":"traceutil/trace.go:171","msg":"trace[1195811394] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.058363577s","start":"2026-02-17T23:11:37.783567Z","end":"2026-02-17T23:11:38.841931Z","steps":["trace[1195811394] 'process raft request' (duration: 1.058077592s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:38.84203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:37.783563Z","time spent":"1.058418549s","remote":"127.0.0.1:37650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:38.843854Z","caller":"traceutil/trace.go:171","msg":"trace[1372973363] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.056580535s","start":"2026-02-17T23:11:37.787266Z","end":"2026-02-17T23:11:38.843847Z","steps":["trace[1372973363] 'process raft request' (duration: 1.054469786s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:38.844042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:37.787259Z","time spent":"1.056613876s","remote":"127.0.0.1:37394","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-17T23:11:38.962802Z","caller":"traceutil/trace.go:171","msg":"trace[729584726] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:693; }","duration":"603.310644ms","start":"2026-02-17T23:11:38.359477Z","end":"2026-02-17T23:11:38.962788Z","steps":["trace[729584726] 'read index received' (duration: 482.290174ms)","trace[729584726] 'applied index is now lower than readState.Index' (duration: 121.01999ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:38.962941Z","caller":"traceutil/trace.go:171","msg":"trace[1848092685] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"904.448261ms","start":"2026-02-17T23:11:38.058487Z","end":"2026-02-17T23:11:38.962936Z","steps":["trace[1848092685] 'process raft request' (duration: 901.554067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:38.963005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.05847Z","time spent":"904.486912ms","remote":"127.0.0.1:37670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:38.963194Z","caller":"traceutil/trace.go:171","msg":"trace[2036422748] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"698.095672ms","start":"2026-02-17T23:11:38.265061Z","end":"2026-02-17T23:11:38.963157Z","steps":["trace[2036422748] 'process raft request' (duration: 697.675434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:38.963221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.265036Z","time spent":"698.170522ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:38.963346Z","caller":"traceutil/trace.go:171","msg":"trace[527846760] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"356.496256ms","start":"2026-02-17T23:11:38.606845Z","end":"2026-02-17T23:11:38.963341Z","steps":["trace[527846760] 'process raft request' (duration: 355.918295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:38.965372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.606827Z","time spent":"356.529756ms","remote":"127.0.0.1:37478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:38.965493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.014014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:38.965515Z","caller":"traceutil/trace.go:171","msg":"trace[1693654492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"606.053036ms","start":"2026-02-17T23:11:38.359454Z","end":"2026-02-17T23:11:38.965507Z","steps":["trace[1693654492] 'agreement among raft nodes before linearized reading' (duration: 606.018105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:38.965541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.35944Z","time spent":"606.097336ms","remote":"127.0.0.1:37186","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-17T23:11:39.031133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.511618ms","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-17T23:11:39.03117Z","caller":"traceutil/trace.go:171","msg":"trace[274461491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"194.599489ms","start":"2026-02-17T23:11:38.836565Z","end":"2026-02-17T23:11:39.031164Z","steps":["trace[274461491] 'agreement among raft nodes before linearized reading' (duration: 194.450716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:39.031348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.823163ms","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-17T23:11:39.031362Z","caller":"traceutil/trace.go:171","msg":"trace[651824540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"443.857904ms","start":"2026-02-17T23:11:38.5875Z","end":"2026-02-17T23:11:39.031358Z","steps":["trace[651824540] 'agreement among raft nodes before linearized reading' (duration: 443.801552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:39.031377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.58749Z","time spent":"443.879975ms","remote":"127.0.0.1:37464","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-17T23:11:39.031461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.949505ms","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-17T23:11:39.031473Z","caller":"traceutil/trace.go:171","msg":"trace[1506474272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"444.989105ms","start":"2026-02-17T23:11:38.58648Z","end":"2026-02-17T23:11:39.031469Z","steps":["trace[1506474272] 'agreement among raft nodes before linearized reading' (duration: 444.952925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:39.031484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.586466Z","time spent":"445.015756ms","remote":"127.0.0.1:37464","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-17T23:11:39.031578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.87426ms","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-17T23:11:39.031591Z","caller":"traceutil/trace.go:171","msg":"trace[2006238183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"471.909621ms","start":"2026-02-17T23:11:38.559677Z","end":"2026-02-17T23:11:39.031587Z","steps":["trace[2006238183] 'agreement among raft nodes before linearized reading' (duration: 471.85532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:39.031643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:38.559662Z","time spent":"471.937301ms","remote":"127.0.0.1:37464","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-17T23:11:39.031877Z","caller":"traceutil/trace.go:171","msg":"trace[1722135450] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"184.601132ms","start":"2026-02-17T23:11:38.847269Z","end":"2026-02-17T23:11:39.03187Z","steps":["trace[1722135450] 'process raft request' (duration: 183.523981ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:39.030967Z","caller":"traceutil/trace.go:171","msg":"trace[413138714] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"181.977803ms","start":"2026-02-17T23:11:38.848974Z","end":"2026-02-17T23:11:39.030952Z","steps":["trace[413138714] 'process raft request' (duration: 181.942362ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:39.074848Z","caller":"traceutil/trace.go:171","msg":"trace[295069833] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"105.721363ms","start":"2026-02-17T23:11:38.969111Z","end":"2026-02-17T23:11:39.074832Z","steps":["trace[295069833] 'process raft request' (duration: 105.202323ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:39.075395Z","caller":"traceutil/trace.go:171","msg":"trace[1611115242] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"104.443158ms","start":"2026-02-17T23:11:38.970946Z","end":"2026-02-17T23:11:39.07539Z","steps":["trace[1611115242] 'process raft request' (duration: 103.828547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:39.081534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.118295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:39.081573Z","caller":"traceutil/trace.go:171","msg":"trace[844262025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"110.183316ms","start":"2026-02-17T23:11:38.97138Z","end":"2026-02-17T23:11:39.081563Z","steps":["trace[844262025] 'agreement among raft nodes before linearized reading' (duration: 103.419539ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:40.83758Z","caller":"traceutil/trace.go:171","msg":"trace[1752567024] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"138.761092ms","start":"2026-02-17T23:11:40.698801Z","end":"2026-02-17T23:11:40.837562Z","steps":["trace[1752567024] 'process raft request' (duration: 138.67865ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:41.516618Z","caller":"traceutil/trace.go:171","msg":"trace[1027153811] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"426.383243ms","start":"2026-02-17T23:11:41.090219Z","end":"2026-02-17T23:11:41.516602Z","steps":["trace[1027153811] 'process raft request' (duration: 426.27058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:41.516727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:41.0902Z","time spent":"426.469324ms","remote":"127.0.0.1:37464","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-17T23:11:42.981459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.656874ms","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-17T23:11:42.981553Z","caller":"traceutil/trace.go:171","msg":"trace[313165374] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:687; }","duration":"887.776118ms","start":"2026-02-17T23:11:42.093763Z","end":"2026-02-17T23:11:42.981539Z","steps":["trace[313165374] 'range keys from in-memory index tree' (duration: 887.640294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:42.982248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.150649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:42.982388Z","caller":"traceutil/trace.go:171","msg":"trace[2058559238] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"1.675260171s","start":"2026-02-17T23:11:41.307119Z","end":"2026-02-17T23:11:42.98238Z","steps":["trace[2058559238] 'read index received' (duration: 209.951876ms)","trace[2058559238] 'applied index is now lower than readState.Index' (duration: 1.465307725s)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:42.982544Z","caller":"traceutil/trace.go:171","msg":"trace[1309303121] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.890384965s","start":"2026-02-17T23:11:41.09215Z","end":"2026-02-17T23:11:42.982535Z","steps":["trace[1309303121] 'process raft request' (duration: 1.156781396s)","trace[1309303121] 'compare' (duration: 732.302319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:42.982592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:41.092131Z","time spent":"1.890438586s","remote":"127.0.0.1:37464","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-17T23:11:42.982703Z","caller":"traceutil/trace.go:171","msg":"trace[1240584549] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.890518528s","start":"2026-02-17T23:11:41.092178Z","end":"2026-02-17T23:11:42.982697Z","steps":["trace[1240584549] 'process raft request' (duration: 1.890116639s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:42.982741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:41.092162Z","time spent":"1.890560279s","remote":"127.0.0.1:37464","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-17T23:11:42.982848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620478535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:42.982902Z","caller":"traceutil/trace.go:171","msg":"trace[1464289509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"1.620577057s","start":"2026-02-17T23:11:41.362311Z","end":"2026-02-17T23:11:42.982888Z","steps":["trace[1464289509] 'agreement among raft nodes before linearized reading' (duration: 1.620470835s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:42.98293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:41.362292Z","time spent":"1.620632548s","remote":"127.0.0.1:37186","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-17T23:11:42.983075Z","caller":"traceutil/trace.go:171","msg":"trace[281648174] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.889676893s","start":"2026-02-17T23:11:41.093388Z","end":"2026-02-17T23:11:42.983065Z","steps":["trace[281648174] 'process raft request' (duration: 1.888967828s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:42.983136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676024718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-17T23:11:42.983142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:41.093379Z","time spent":"1.889713144s","remote":"127.0.0.1:37464","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-17T23:11:42.983162Z","caller":"traceutil/trace.go:171","msg":"trace[1849550919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"1.676087059s","start":"2026-02-17T23:11:41.307068Z","end":"2026-02-17T23:11:42.983155Z","steps":["trace[1849550919] 'agreement among raft nodes before linearized reading' (duration: 1.676005217s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:42.983185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:41.307044Z","time spent":"1.676133421s","remote":"127.0.0.1:37464","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-17T23:11:43.503169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297723591,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:11:43.740497Z","caller":"traceutil/trace.go:171","msg":"trace[1765984906] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"738.407936ms","start":"2026-02-17T23:11:43.002073Z","end":"2026-02-17T23:11:43.740481Z","steps":["trace[1765984906] 'read index received' (duration: 736.280928ms)","trace[1765984906] 'applied index is now lower than readState.Index' (duration: 2.126318ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:43.740668Z","caller":"traceutil/trace.go:171","msg":"trace[2087472027] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"742.025346ms","start":"2026-02-17T23:11:42.998636Z","end":"2026-02-17T23:11:43.740661Z","steps":["trace[2087472027] 'process raft request' (duration: 739.777356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:43.740752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:42.998624Z","time spent":"742.063027ms","remote":"127.0.0.1:37464","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-17T23:11:43.740872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.798165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:43.740895Z","caller":"traceutil/trace.go:171","msg":"trace[257649154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"738.848135ms","start":"2026-02-17T23:11:43.002039Z","end":"2026-02-17T23:11:43.740887Z","steps":["trace[257649154] 'agreement among raft nodes before linearized reading' (duration: 738.814825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:43.740913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.002024Z","time spent":"738.885257ms","remote":"127.0.0.1:37186","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-17T23:11:43.741055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.675001ms","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-17T23:11:43.741076Z","caller":"traceutil/trace.go:171","msg":"trace[815597201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"218.732181ms","start":"2026-02-17T23:11:43.522338Z","end":"2026-02-17T23:11:43.741071Z","steps":["trace[815597201] 'agreement among raft nodes before linearized reading' (duration: 218.66192ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:45.354133Z","caller":"traceutil/trace.go:171","msg":"trace[457938011] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"351.82829ms","start":"2026-02-17T23:11:45.002285Z","end":"2026-02-17T23:11:45.354114Z","steps":["trace[457938011] 'process raft request' (duration: 351.724977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.354254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:45.002275Z","time spent":"351.911272ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:45.491157Z","caller":"traceutil/trace.go:171","msg":"trace[501285686] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"308.416948ms","start":"2026-02-17T23:11:45.182724Z","end":"2026-02-17T23:11:45.491141Z","steps":["trace[501285686] 'read index received' (duration: 171.850564ms)","trace[501285686] 'applied index is now lower than readState.Index' (duration: 136.565624ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:45.491442Z","caller":"traceutil/trace.go:171","msg":"trace[1133628619] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"485.487279ms","start":"2026-02-17T23:11:45.005942Z","end":"2026-02-17T23:11:45.491429Z","steps":["trace[1133628619] 'process raft request' (duration: 433.057907ms)","trace[1133628619] 'compare' (duration: 52.023112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:45.49152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:45.005923Z","time spent":"485.545761ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:45.491691Z","caller":"traceutil/trace.go:171","msg":"trace[653839569] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"484.474746ms","start":"2026-02-17T23:11:45.007207Z","end":"2026-02-17T23:11:45.491682Z","steps":["trace[653839569] 'process raft request' (duration: 483.905342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.491765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:45.007198Z","time spent":"484.536458ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:45.491922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.197296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T23:11:45.491949Z","caller":"traceutil/trace.go:171","msg":"trace[608761809] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:696; }","duration":"309.254718ms","start":"2026-02-17T23:11:45.182688Z","end":"2026-02-17T23:11:45.491943Z","steps":["trace[608761809] 'agreement among raft nodes before linearized reading' (duration: 309.171156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.49197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:45.182671Z","time spent":"309.292868ms","remote":"127.0.0.1:37216","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.240\" "} {"level":"warn","ts":"2026-02-17T23:11:45.492101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.839487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:45.492123Z","caller":"traceutil/trace.go:171","msg":"trace[1166578892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"130.883587ms","start":"2026-02-17T23:11:45.361232Z","end":"2026-02-17T23:11:45.492116Z","steps":["trace[1166578892] 'agreement among raft nodes before linearized reading' (duration: 130.817126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:46.059255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.481635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:46.059335Z","caller":"traceutil/trace.go:171","msg":"trace[1033309355] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"313.300618ms","start":"2026-02-17T23:11:45.746021Z","end":"2026-02-17T23:11:46.059322Z","steps":["trace[1033309355] 'read index received' (duration: 25.151µs)","trace[1033309355] 'applied index is now lower than readState.Index' (duration: 313.274257ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:46.059354Z","caller":"traceutil/trace.go:171","msg":"trace[1599080151] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"531.115819ms","start":"2026-02-17T23:11:45.528214Z","end":"2026-02-17T23:11:46.05933Z","steps":["trace[1599080151] 'process raft request' (duration: 76.494141ms)","trace[1599080151] 'compare' (duration: 454.332452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:46.059481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:45.528201Z","time spent":"531.201651ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:46.059497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.473202ms","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-17T23:11:46.059517Z","caller":"traceutil/trace.go:171","msg":"trace[1109581461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"313.516652ms","start":"2026-02-17T23:11:45.745993Z","end":"2026-02-17T23:11:46.05951Z","steps":["trace[1109581461] 'agreement among raft nodes before linearized reading' (duration: 313.39426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:46.05954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:45.74598Z","time spent":"313.554284ms","remote":"127.0.0.1:37464","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-17T23:11:46.059722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.391594ms","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-17T23:11:46.05974Z","caller":"traceutil/trace.go:171","msg":"trace[37382054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"298.432404ms","start":"2026-02-17T23:11:45.761302Z","end":"2026-02-17T23:11:46.059735Z","steps":["trace[37382054] 'agreement among raft nodes before linearized reading' (duration: 298.379963ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:47.640073Z","caller":"traceutil/trace.go:171","msg":"trace[733592345] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"142.17074ms","start":"2026-02-17T23:11:47.497868Z","end":"2026-02-17T23:11:47.640039Z","steps":["trace[733592345] 'read index received' (duration: 142.073527ms)","trace[733592345] 'applied index is now lower than readState.Index' (duration: 96.503µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:47.640157Z","caller":"traceutil/trace.go:171","msg":"trace[1108600845] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"279.517943ms","start":"2026-02-17T23:11:47.360631Z","end":"2026-02-17T23:11:47.640149Z","steps":["trace[1108600845] 'process raft request' (duration: 279.322767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:47.640321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.436126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-02-17T23:11:47.640341Z","caller":"traceutil/trace.go:171","msg":"trace[140860376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"142.505278ms","start":"2026-02-17T23:11:47.49783Z","end":"2026-02-17T23:11:47.640335Z","steps":["trace[140860376] 'agreement among raft nodes before linearized reading' (duration: 142.410556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:47.640486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.547229ms","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-17T23:11:47.640532Z","caller":"traceutil/trace.go:171","msg":"trace[508516549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"142.61553ms","start":"2026-02-17T23:11:47.497904Z","end":"2026-02-17T23:11:47.64052Z","steps":["trace[508516549] 'agreement among raft nodes before linearized reading' (duration: 142.493597ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:04.03227Z","caller":"traceutil/trace.go:171","msg":"trace[754913833] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"248.912818ms","start":"2026-02-17T23:12:03.783339Z","end":"2026-02-17T23:12:04.032252Z","steps":["trace[754913833] 'read index received' (duration: 248.749905ms)","trace[754913833] 'applied index is now lower than readState.Index' (duration: 162.293µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:04.032492Z","caller":"traceutil/trace.go:171","msg":"trace[1371467520] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"265.445447ms","start":"2026-02-17T23:12:03.767039Z","end":"2026-02-17T23:12:04.032484Z","steps":["trace[1371467520] 'process raft request' (duration: 265.11185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.032688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.333117ms","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-17T23:12:04.032709Z","caller":"traceutil/trace.go:171","msg":"trace[993773814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"249.383378ms","start":"2026-02-17T23:12:03.783319Z","end":"2026-02-17T23:12:04.032703Z","steps":["trace[993773814] 'agreement among raft nodes before linearized reading' (duration: 249.289266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.032816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.147329ms","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-17T23:12:04.032833Z","caller":"traceutil/trace.go:171","msg":"trace[1729231544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"248.174261ms","start":"2026-02-17T23:12:03.784654Z","end":"2026-02-17T23:12:04.032828Z","steps":["trace[1729231544] 'agreement among raft nodes before linearized reading' (duration: 248.12753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.032991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.990199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:04.033008Z","caller":"traceutil/trace.go:171","msg":"trace[1750217944] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:750; }","duration":"211.061921ms","start":"2026-02-17T23:12:03.821941Z","end":"2026-02-17T23:12:04.033003Z","steps":["trace[1750217944] 'agreement among raft nodes before linearized reading' (duration: 210.999789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.601923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.410863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:04.601999Z","caller":"traceutil/trace.go:171","msg":"trace[1887912608] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"563.588766ms","start":"2026-02-17T23:12:04.038396Z","end":"2026-02-17T23:12:04.601985Z","steps":["trace[1887912608] 'process raft request' (duration: 84.0554ms)","trace[1887912608] 'compare' (duration: 479.316041ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:04.602038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.038381Z","time spent":"563.639197ms","remote":"127.0.0.1:37464","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-17T23:12:04.659973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297723775,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:12:04.996526Z","caller":"traceutil/trace.go:171","msg":"trace[55277257] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"305.268059ms","start":"2026-02-17T23:12:04.691236Z","end":"2026-02-17T23:12:04.996504Z","steps":["trace[55277257] 'process raft request' (duration: 305.217368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.996663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.691219Z","time spent":"305.370441ms","remote":"127.0.0.1:37464","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-17T23:12:04.996678Z","caller":"traceutil/trace.go:171","msg":"trace[1481029805] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"958.134721ms","start":"2026-02-17T23:12:04.038516Z","end":"2026-02-17T23:12:04.996651Z","steps":["trace[1481029805] 'process raft request' (duration: 945.745204ms)","trace[1481029805] 'compare' (duration: 11.711102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:04.996808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.038381Z","time spent":"958.354426ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:04.996851Z","caller":"traceutil/trace.go:171","msg":"trace[1008237922] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"837.668387ms","start":"2026-02-17T23:12:04.159172Z","end":"2026-02-17T23:12:04.99684Z","steps":["trace[1008237922] 'read index received' (duration: 825.144527ms)","trace[1008237922] 'applied index is now lower than readState.Index' (duration: 12.52275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:04.99701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.850381ms","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-17T23:12:04.997044Z","caller":"traceutil/trace.go:171","msg":"trace[1935011022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"837.926042ms","start":"2026-02-17T23:12:04.159109Z","end":"2026-02-17T23:12:04.997035Z","steps":["trace[1935011022] 'agreement among raft nodes before linearized reading' (duration: 837.81369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.997073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.159089Z","time spent":"837.977613ms","remote":"127.0.0.1:37464","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-17T23:12:04.997198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.486876ms","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-17T23:12:04.997227Z","caller":"traceutil/trace.go:171","msg":"trace[1631176675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"822.544817ms","start":"2026-02-17T23:12:04.174674Z","end":"2026-02-17T23:12:04.997219Z","steps":["trace[1631176675] 'agreement among raft nodes before linearized reading' (duration: 822.470376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.997251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.174656Z","time spent":"822.589399ms","remote":"127.0.0.1:37464","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-17T23:12:04.997308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.632616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:04.997338Z","caller":"traceutil/trace.go:171","msg":"trace[1329736672] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:753; }","duration":"334.686367ms","start":"2026-02-17T23:12:04.662643Z","end":"2026-02-17T23:12:04.99733Z","steps":["trace[1329736672] 'agreement among raft nodes before linearized reading' (duration: 334.635256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.997362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.662629Z","time spent":"334.728267ms","remote":"127.0.0.1:37610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-02-17T23:12:05.223769Z","caller":"traceutil/trace.go:171","msg":"trace[713780623] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"217.030814ms","start":"2026-02-17T23:12:05.006717Z","end":"2026-02-17T23:12:05.223748Z","steps":["trace[713780623] 'process raft request' (duration: 121.536469ms)","trace[713780623] 'compare' (duration: 94.962993ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:06.590221Z","caller":"traceutil/trace.go:171","msg":"trace[21507024] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"228.920191ms","start":"2026-02-17T23:12:06.361281Z","end":"2026-02-17T23:12:06.590201Z","steps":["trace[21507024] 'read index received' (duration: 228.678586ms)","trace[21507024] 'applied index is now lower than readState.Index' (duration: 240.775µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:06.590323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.022383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:06.590377Z","caller":"traceutil/trace.go:171","msg":"trace[1900547952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"229.122535ms","start":"2026-02-17T23:12:06.361245Z","end":"2026-02-17T23:12:06.590367Z","steps":["trace[1900547952] 'agreement among raft nodes before linearized reading' (duration: 229.034653ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:06.590539Z","caller":"traceutil/trace.go:171","msg":"trace[133430709] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"544.174491ms","start":"2026-02-17T23:12:06.046337Z","end":"2026-02-17T23:12:06.590512Z","steps":["trace[133430709] 'process raft request' (duration: 543.66857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:06.590716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:06.046323Z","time spent":"544.302625ms","remote":"127.0.0.1:37464","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-17T23:12:07.087798Z","caller":"traceutil/trace.go:171","msg":"trace[183985336] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"475.570727ms","start":"2026-02-17T23:12:06.612212Z","end":"2026-02-17T23:12:07.087783Z","steps":["trace[183985336] 'process raft request' (duration: 475.340592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:07.08789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:06.612202Z","time spent":"475.639458ms","remote":"127.0.0.1:37464","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-17T23:12:08.170349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.85246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:08.170419Z","caller":"traceutil/trace.go:171","msg":"trace[1818297920] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:761; }","duration":"689.965263ms","start":"2026-02-17T23:12:07.480437Z","end":"2026-02-17T23:12:08.170402Z","steps":["trace[1818297920] 'count revisions from in-memory index tree' (duration: 689.758628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:08.170483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:07.48042Z","time spent":"690.050924ms","remote":"127.0.0.1:37302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:08.170651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.89587ms","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-17T23:12:08.170681Z","caller":"traceutil/trace.go:171","msg":"trace[965620764] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:761; }","duration":"646.964081ms","start":"2026-02-17T23:12:07.523706Z","end":"2026-02-17T23:12:08.170671Z","steps":["trace[965620764] 'count revisions from in-memory index tree' (duration: 646.845419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:08.170708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:07.523691Z","time spent":"647.009052ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-17T23:12:08.729444Z","caller":"traceutil/trace.go:171","msg":"trace[72494723] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"116.968676ms","start":"2026-02-17T23:12:08.612431Z","end":"2026-02-17T23:12:08.7294Z","steps":["trace[72494723] 'process raft request' (duration: 116.785552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.377227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.350167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T23:12:11.377319Z","caller":"traceutil/trace.go:171","msg":"trace[2043214459] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"644.583577ms","start":"2026-02-17T23:12:10.73272Z","end":"2026-02-17T23:12:11.377303Z","steps":["trace[2043214459] 'read index received' (duration: 319.077507ms)","trace[2043214459] 'applied index is now lower than readState.Index' (duration: 325.50482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:11.377499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.709865ms","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":"warn","ts":"2026-02-17T23:12:11.377543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.874451ms","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-17T23:12:11.377564Z","caller":"traceutil/trace.go:171","msg":"trace[21597943] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:767; }","duration":"422.783777ms","start":"2026-02-17T23:12:10.954764Z","end":"2026-02-17T23:12:11.377548Z","steps":["trace[21597943] 'agreement among raft nodes before linearized reading' (duration: 422.642584ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:11.377573Z","caller":"traceutil/trace.go:171","msg":"trace[1355442464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:767; }","duration":"127.944032ms","start":"2026-02-17T23:12:11.249619Z","end":"2026-02-17T23:12:11.377563Z","steps":["trace[1355442464] 'agreement among raft nodes before linearized reading' (duration: 127.784259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.377604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:10.954738Z","time spent":"422.855759ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:11.378217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.488017ms","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-17T23:12:11.378592Z","caller":"traceutil/trace.go:171","msg":"trace[1497044322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"645.878916ms","start":"2026-02-17T23:12:10.732696Z","end":"2026-02-17T23:12:11.378574Z","steps":["trace[1497044322] 'agreement among raft nodes before linearized reading' (duration: 645.447697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.378927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:10.732684Z","time spent":"646.224405ms","remote":"127.0.0.1:37464","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-17T23:12:11.3784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.395128ms","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-17T23:12:11.379372Z","caller":"traceutil/trace.go:171","msg":"trace[1548654271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"250.381621ms","start":"2026-02-17T23:12:11.128976Z","end":"2026-02-17T23:12:11.379358Z","steps":["trace[1548654271] 'agreement among raft nodes before linearized reading' (duration: 249.324576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.378668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.32305ms","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-17T23:12:11.379954Z","caller":"traceutil/trace.go:171","msg":"trace[1937154757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"264.462636ms","start":"2026-02-17T23:12:11.115317Z","end":"2026-02-17T23:12:11.379779Z","steps":["trace[1937154757] 'agreement among raft nodes before linearized reading' (duration: 263.148296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.378742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.440173ms","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-17T23:12:11.38036Z","caller":"traceutil/trace.go:171","msg":"trace[1953311228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"265.085429ms","start":"2026-02-17T23:12:11.11526Z","end":"2026-02-17T23:12:11.380346Z","steps":["trace[1953311228] 'agreement among raft nodes before linearized reading' (duration: 263.431782ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:11.712579Z","caller":"traceutil/trace.go:171","msg":"trace[441208213] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"323.63528ms","start":"2026-02-17T23:12:11.388927Z","end":"2026-02-17T23:12:11.712563Z","steps":["trace[441208213] 'process raft request' (duration: 323.491157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.712696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:11.388911Z","time spent":"323.725371ms","remote":"127.0.0.1:37464","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-17T23:12:11.712772Z","caller":"traceutil/trace.go:171","msg":"trace[1120293734] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"323.750132ms","start":"2026-02-17T23:12:11.389014Z","end":"2026-02-17T23:12:11.712764Z","steps":["trace[1120293734] 'process raft request' (duration: 323.522387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.712827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:11.389009Z","time spent":"323.798883ms","remote":"127.0.0.1:37464","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-17T23:12:11.712945Z","caller":"traceutil/trace.go:171","msg":"trace[2095706670] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"321.173444ms","start":"2026-02-17T23:12:11.391767Z","end":"2026-02-17T23:12:11.71294Z","steps":["trace[2095706670] 'process raft request' (duration: 320.940939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.712999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:11.391759Z","time spent":"321.224035ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:11.713094Z","caller":"traceutil/trace.go:171","msg":"trace[1305748913] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"321.276336ms","start":"2026-02-17T23:12:11.391813Z","end":"2026-02-17T23:12:11.713089Z","steps":["trace[1305748913] 'process raft request' (duration: 321.104403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:11.713145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:11.391805Z","time spent":"321.324637ms","remote":"127.0.0.1:37464","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-17T23:12:24.154258Z","caller":"traceutil/trace.go:171","msg":"trace[1575512714] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"386.341802ms","start":"2026-02-17T23:12:23.767901Z","end":"2026-02-17T23:12:24.154243Z","steps":["trace[1575512714] 'read index received' (duration: 386.198229ms)","trace[1575512714] 'applied index is now lower than readState.Index' (duration: 143.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:24.154533Z","caller":"traceutil/trace.go:171","msg":"trace[1763736246] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"619.67512ms","start":"2026-02-17T23:12:23.534821Z","end":"2026-02-17T23:12:24.154496Z","steps":["trace[1763736246] 'process raft request' (duration: 619.331363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:24.154605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:23.534795Z","time spent":"619.753102ms","remote":"127.0.0.1:37464","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-17T23:12:24.154765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.861393ms","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-17T23:12:24.154794Z","caller":"traceutil/trace.go:171","msg":"trace[73233382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"386.915654ms","start":"2026-02-17T23:12:23.767873Z","end":"2026-02-17T23:12:24.154789Z","steps":["trace[73233382] 'agreement among raft nodes before linearized reading' (duration: 386.831232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:24.154809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:23.767861Z","time spent":"386.944164ms","remote":"127.0.0.1:37464","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-17T23:12:24.154911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.576292ms","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-17T23:12:24.154924Z","caller":"traceutil/trace.go:171","msg":"trace[1066784158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"380.602744ms","start":"2026-02-17T23:12:23.774317Z","end":"2026-02-17T23:12:24.15492Z","steps":["trace[1066784158] 'agreement among raft nodes before linearized reading' (duration: 380.563843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:24.154956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:23.77431Z","time spent":"380.629244ms","remote":"127.0.0.1:37464","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-17T23:12:24.15509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.973415ms","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-17T23:12:24.155105Z","caller":"traceutil/trace.go:171","msg":"trace[359858904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"353.008526ms","start":"2026-02-17T23:12:23.802092Z","end":"2026-02-17T23:12:24.155101Z","steps":["trace[359858904] 'agreement among raft nodes before linearized reading' (duration: 352.968825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:24.15514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:23.802083Z","time spent":"353.052807ms","remote":"127.0.0.1:37464","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-17T23:12:24.155231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.758019ms","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-17T23:12:24.155243Z","caller":"traceutil/trace.go:171","msg":"trace[961973237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"364.79174ms","start":"2026-02-17T23:12:23.790447Z","end":"2026-02-17T23:12:24.155239Z","steps":["trace[961973237] 'agreement among raft nodes before linearized reading' (duration: 364.756609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:24.155254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:23.790437Z","time spent":"364.81378ms","remote":"127.0.0.1:37464","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-17T23:12:24.739847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297724014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T23:12:25.240822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297724014,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T23:12:25.443721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.281513703s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T23:12:25.444005Z","caller":"traceutil/trace.go:171","msg":"trace[125251933] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"1.281892051s","start":"2026-02-17T23:12:24.162099Z","end":"2026-02-17T23:12:25.443991Z","steps":["trace[125251933] 'process raft request' (duration: 1.281789819s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:25.444139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:24.162082Z","time spent":"1.281949643s","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:25.471986Z","caller":"traceutil/trace.go:171","msg":"trace[291600049] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:886; }","duration":"1.233195973s","start":"2026-02-17T23:12:24.23877Z","end":"2026-02-17T23:12:25.471966Z","steps":["trace[291600049] 'read index received' (duration: 1.205101105s)","trace[291600049] 'applied index is now lower than readState.Index' (duration: 28.094258ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:25.472268Z","caller":"traceutil/trace.go:171","msg":"trace[1882393234] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"1.307862143s","start":"2026-02-17T23:12:24.164399Z","end":"2026-02-17T23:12:25.472261Z","steps":["trace[1882393234] 'process raft request' (duration: 1.307403993s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:25.472344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:24.164385Z","time spent":"1.307902754s","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:25.472454Z","caller":"traceutil/trace.go:171","msg":"trace[482061865] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"1.307995976s","start":"2026-02-17T23:12:24.164452Z","end":"2026-02-17T23:12:25.472448Z","steps":["trace[482061865] 'process raft request' (duration: 1.307445794s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:25.472487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:24.164448Z","time spent":"1.308019527s","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:25.472554Z","caller":"traceutil/trace.go:171","msg":"trace[1777437469] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"1.308045337s","start":"2026-02-17T23:12:24.164503Z","end":"2026-02-17T23:12:25.472549Z","steps":["trace[1777437469] 'process raft request' (duration: 1.307416412s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:25.472583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:24.1645Z","time spent":"1.308067238s","remote":"127.0.0.1:37464","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-17T23:12:25.472708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233939311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:25.47273Z","caller":"traceutil/trace.go:171","msg":"trace[782718322] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:857; }","duration":"1.234011072s","start":"2026-02-17T23:12:24.238712Z","end":"2026-02-17T23:12:25.472723Z","steps":["trace[782718322] 'agreement among raft nodes before linearized reading' (duration: 1.233972731s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:25.472754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:24.238696Z","time spent":"1.234050414s","remote":"127.0.0.1:37666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:25.472841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114951689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:25.472857Z","caller":"traceutil/trace.go:171","msg":"trace[609537209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"1.114990849s","start":"2026-02-17T23:12:24.357861Z","end":"2026-02-17T23:12:25.472852Z","steps":["trace[609537209] 'agreement among raft nodes before linearized reading' (duration: 1.114966698s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:25.472872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:24.357848Z","time spent":"1.1150209s","remote":"127.0.0.1:37186","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-17T23:12:25.473064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.455442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T23:12:25.473082Z","caller":"traceutil/trace.go:171","msg":"trace[341531386] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:857; }","duration":"288.507174ms","start":"2026-02-17T23:12:25.18457Z","end":"2026-02-17T23:12:25.473077Z","steps":["trace[341531386] 'agreement among raft nodes before linearized reading' (duration: 288.436712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:26.124303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.356783ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-17T23:12:26.124386Z","caller":"traceutil/trace.go:171","msg":"trace[1119341774] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"632.444086ms","start":"2026-02-17T23:12:25.491927Z","end":"2026-02-17T23:12:26.124371Z","steps":["trace[1119341774] 'read index received' (duration: 156.94599ms)","trace[1119341774] 'applied index is now lower than readState.Index' (duration: 475.496656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:26.124494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.560658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:26.124519Z","caller":"traceutil/trace.go:171","msg":"trace[738186202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"632.61439ms","start":"2026-02-17T23:12:25.491896Z","end":"2026-02-17T23:12:26.12451Z","steps":["trace[738186202] 'agreement among raft nodes before linearized reading' (duration: 632.517088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:26.124551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:25.49188Z","time spent":"632.661741ms","remote":"127.0.0.1:37186","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-17T23:12:26.124758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:25.475817Z","time spent":"648.939924ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T23:12:35.86259Z","caller":"traceutil/trace.go:171","msg":"trace[497726375] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"372.268326ms","start":"2026-02-17T23:12:35.490306Z","end":"2026-02-17T23:12:35.862574Z","steps":["trace[497726375] 'process raft request' (duration: 372.176214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:35.862687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:35.490297Z","time spent":"372.338448ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:35.862994Z","caller":"traceutil/trace.go:171","msg":"trace[349597349] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:919; }","duration":"354.650412ms","start":"2026-02-17T23:12:35.508338Z","end":"2026-02-17T23:12:35.862989Z","steps":["trace[349597349] 'read index received' (duration: 354.647932ms)","trace[349597349] 'applied index is now lower than readState.Index' (duration: 2.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:35.863073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.738215ms","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-17T23:12:35.863087Z","caller":"traceutil/trace.go:171","msg":"trace[1660818688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"354.777925ms","start":"2026-02-17T23:12:35.508304Z","end":"2026-02-17T23:12:35.863082Z","steps":["trace[1660818688] 'agreement among raft nodes before linearized reading' (duration: 354.719364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:35.863102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:35.508288Z","time spent":"354.808976ms","remote":"127.0.0.1:37464","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-17T23:12:35.875227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.81981ms","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-17T23:12:35.875259Z","caller":"traceutil/trace.go:171","msg":"trace[742450110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"356.867561ms","start":"2026-02-17T23:12:35.518384Z","end":"2026-02-17T23:12:35.875252Z","steps":["trace[742450110] 'agreement among raft nodes before linearized reading' (duration: 356.773439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:35.875276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:35.518379Z","time spent":"356.893301ms","remote":"127.0.0.1:37464","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-17T23:12:35.875353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.912912ms","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-17T23:12:35.875364Z","caller":"traceutil/trace.go:171","msg":"trace[1427867633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"356.931552ms","start":"2026-02-17T23:12:35.518429Z","end":"2026-02-17T23:12:35.875361Z","steps":["trace[1427867633] 'agreement among raft nodes before linearized reading' (duration: 356.897552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:35.875376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:35.518427Z","time spent":"356.946223ms","remote":"127.0.0.1:37464","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-17T23:12:39.022982Z","caller":"traceutil/trace.go:171","msg":"trace[1035558456] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"327.935045ms","start":"2026-02-17T23:12:38.695024Z","end":"2026-02-17T23:12:39.022959Z","steps":["trace[1035558456] 'read index received' (duration: 327.73601ms)","trace[1035558456] 'applied index is now lower than readState.Index' (duration: 198.145µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:39.025685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.948685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:39.025745Z","caller":"traceutil/trace.go:171","msg":"trace[1306467642] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:892; }","duration":"167.073258ms","start":"2026-02-17T23:12:38.858657Z","end":"2026-02-17T23:12:39.02573Z","steps":["trace[1306467642] 'agreement among raft nodes before linearized reading' (duration: 166.949545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:39.026661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.632467ms","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-17T23:12:39.026743Z","caller":"traceutil/trace.go:171","msg":"trace[496681757] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:892; }","duration":"331.72581ms","start":"2026-02-17T23:12:38.694998Z","end":"2026-02-17T23:12:39.026723Z","steps":["trace[496681757] 'agreement among raft nodes before linearized reading' (duration: 331.632328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:39.026795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:38.694987Z","time spent":"331.7718ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-17T23:12:39.027148Z","caller":"traceutil/trace.go:171","msg":"trace[1248420887] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"624.57844ms","start":"2026-02-17T23:12:38.402561Z","end":"2026-02-17T23:12:39.02714Z","steps":["trace[1248420887] 'process raft request' (duration: 620.271334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:39.027209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:38.402553Z","time spent":"624.616651ms","remote":"127.0.0.1:37464","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-17T23:12:40.020007Z","caller":"traceutil/trace.go:171","msg":"trace[1618573266] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"102.087703ms","start":"2026-02-17T23:12:39.917902Z","end":"2026-02-17T23:12:40.019989Z","steps":["trace[1618573266] 'process raft request' (duration: 86.71034ms)","trace[1618573266] 'compare' (duration: 15.2169ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:48.019778Z","caller":"traceutil/trace.go:171","msg":"trace[284035069] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"214.383503ms","start":"2026-02-17T23:12:47.805373Z","end":"2026-02-17T23:12:48.019757Z","steps":["trace[284035069] 'read index received' (duration: 214.067629ms)","trace[284035069] 'applied index is now lower than readState.Index' (duration: 315.044µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:48.020008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.602032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-m2p72\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-17T23:12:48.020379Z","caller":"traceutil/trace.go:171","msg":"trace[469616696] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"624.5039ms","start":"2026-02-17T23:12:47.395863Z","end":"2026-02-17T23:12:48.020367Z","steps":["trace[469616696] 'process raft request' (duration: 623.639533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.020472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:47.395843Z","time spent":"624.574544ms","remote":"127.0.0.1:37378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4909,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:48.020049Z","caller":"traceutil/trace.go:171","msg":"trace[201920854] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-m2p72; range_end:; response_count:1; response_revision:930; }","duration":"214.694306ms","start":"2026-02-17T23:12:47.805344Z","end":"2026-02-17T23:12:48.020038Z","steps":["trace[201920854] 'agreement among raft nodes before linearized reading' (duration: 214.497308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.828966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.02478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:48.829019Z","caller":"traceutil/trace.go:171","msg":"trace[2040474279] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"761.578905ms","start":"2026-02-17T23:12:48.067431Z","end":"2026-02-17T23:12:48.82901Z","steps":["trace[2040474279] 'read index received' (duration: 301.500743ms)","trace[2040474279] 'applied index is now lower than readState.Index' (duration: 460.077512ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:48.829134Z","caller":"traceutil/trace.go:171","msg":"trace[2066127506] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"794.43844ms","start":"2026-02-17T23:12:48.03469Z","end":"2026-02-17T23:12:48.829128Z","steps":["trace[2066127506] 'process raft request' (duration: 334.204391ms)","trace[2066127506] 'compare' (duration: 459.80241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:48.829163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.034675Z","time spent":"794.473302ms","remote":"127.0.0.1:37394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:48.829582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.103322ms","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-17T23:12:48.829599Z","caller":"traceutil/trace.go:171","msg":"trace[1927779374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"748.148085ms","start":"2026-02-17T23:12:48.081445Z","end":"2026-02-17T23:12:48.829593Z","steps":["trace[1927779374] 'agreement among raft nodes before linearized reading' (duration: 748.085982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.829613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.08143Z","time spent":"748.180576ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T23:12:48.829699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.823729ms","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-17T23:12:48.829711Z","caller":"traceutil/trace.go:171","msg":"trace[1947871459] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:931; }","duration":"499.866011ms","start":"2026-02-17T23:12:48.329841Z","end":"2026-02-17T23:12:48.829707Z","steps":["trace[1947871459] 'agreement among raft nodes before linearized reading' (duration: 499.84062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.829727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.329826Z","time spent":"499.896333ms","remote":"127.0.0.1:37552","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":"warn","ts":"2026-02-17T23:12:48.829808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.460434ms","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-17T23:12:48.82982Z","caller":"traceutil/trace.go:171","msg":"trace[721521189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"613.486446ms","start":"2026-02-17T23:12:48.21633Z","end":"2026-02-17T23:12:48.829817Z","steps":["trace[721521189] 'agreement among raft nodes before linearized reading' (duration: 613.448414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.829832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.216323Z","time spent":"613.505846ms","remote":"127.0.0.1:37464","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-17T23:12:48.829902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.275329ms","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-17T23:12:48.829913Z","caller":"traceutil/trace.go:171","msg":"trace[2063780103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"614.321882ms","start":"2026-02-17T23:12:48.215588Z","end":"2026-02-17T23:12:48.82991Z","steps":["trace[2063780103] 'agreement among raft nodes before linearized reading' (duration: 614.29057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.829924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.21557Z","time spent":"614.351173ms","remote":"127.0.0.1:37464","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-17T23:12:48.829995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.536713ms","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-17T23:12:48.830007Z","caller":"traceutil/trace.go:171","msg":"trace[904092022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"622.575895ms","start":"2026-02-17T23:12:48.207427Z","end":"2026-02-17T23:12:48.830003Z","steps":["trace[904092022] 'agreement among raft nodes before linearized reading' (duration: 622.543173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:48.830018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.207411Z","time spent":"622.604456ms","remote":"127.0.0.1:37464","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-17T23:12:48.933608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.166932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-17T23:12:48.941851Z","caller":"traceutil/trace.go:171","msg":"trace[623383211] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:931; }","duration":"866.32915ms","start":"2026-02-17T23:12:48.067408Z","end":"2026-02-17T23:12:48.933737Z","steps":["trace[623383211] 'agreement among raft nodes before linearized reading' (duration: 761.831106ms)","trace[623383211] 'range keys from bolt db' (duration: 104.333296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:48.941913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.067401Z","time spent":"874.491658ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-17T23:12:49.587248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297724215,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:12:49.742231Z","caller":"traceutil/trace.go:171","msg":"trace[894154569] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"906.1667ms","start":"2026-02-17T23:12:48.83604Z","end":"2026-02-17T23:12:49.742206Z","steps":["trace[894154569] 'process raft request' (duration: 891.849591ms)","trace[894154569] 'compare' (duration: 14.059518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:49.742321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.836028Z","time spent":"906.243183ms","remote":"127.0.0.1:37464","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-17T23:12:49.747144Z","caller":"traceutil/trace.go:171","msg":"trace[1159367254] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:970; }","duration":"660.965053ms","start":"2026-02-17T23:12:49.086169Z","end":"2026-02-17T23:12:49.747134Z","steps":["trace[1159367254] 'read index received' (duration: 641.736988ms)","trace[1159367254] 'applied index is now lower than readState.Index' (duration: 19.227295ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:49.747616Z","caller":"traceutil/trace.go:171","msg":"trace[220087080] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"909.907286ms","start":"2026-02-17T23:12:48.837702Z","end":"2026-02-17T23:12:49.747609Z","steps":["trace[220087080] 'process raft request' (duration: 909.217946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.747685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.837695Z","time spent":"909.937387ms","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:49.74784Z","caller":"traceutil/trace.go:171","msg":"trace[305948194] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"907.72311ms","start":"2026-02-17T23:12:48.840111Z","end":"2026-02-17T23:12:49.747834Z","steps":["trace[305948194] 'process raft request' (duration: 906.878283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.747867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.84009Z","time spent":"907.762801ms","remote":"127.0.0.1:37478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:49.747982Z","caller":"traceutil/trace.go:171","msg":"trace[763321139] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"905.377026ms","start":"2026-02-17T23:12:48.842601Z","end":"2026-02-17T23:12:49.747978Z","steps":["trace[763321139] 'process raft request' (duration: 904.419984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.748008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.842595Z","time spent":"905.398957ms","remote":"127.0.0.1:37666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:49.757987Z","caller":"traceutil/trace.go:171","msg":"trace[1369074080] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"914.433235ms","start":"2026-02-17T23:12:48.843521Z","end":"2026-02-17T23:12:49.757954Z","steps":["trace[1369074080] 'process raft request' (duration: 903.520415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.843514Z","time spent":"914.513848ms","remote":"127.0.0.1:37464","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-17T23:12:49.758201Z","caller":"traceutil/trace.go:171","msg":"trace[706179789] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"913.562895ms","start":"2026-02-17T23:12:48.844632Z","end":"2026-02-17T23:12:49.758195Z","steps":["trace[706179789] 'process raft request' (duration: 902.427886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.844627Z","time spent":"913.584067ms","remote":"127.0.0.1:37464","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-17T23:12:49.758284Z","caller":"traceutil/trace.go:171","msg":"trace[1848770051] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"909.138722ms","start":"2026-02-17T23:12:48.849141Z","end":"2026-02-17T23:12:49.75828Z","steps":["trace[1848770051] 'process raft request' (duration: 897.93379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.849134Z","time spent":"909.160653ms","remote":"127.0.0.1:37464","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-17T23:12:49.75842Z","caller":"traceutil/trace.go:171","msg":"trace[920022616] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"810.565459ms","start":"2026-02-17T23:12:48.94785Z","end":"2026-02-17T23:12:49.758416Z","steps":["trace[920022616] 'process raft request' (duration: 799.238931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:48.947837Z","time spent":"810.595141ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:49.75856Z","caller":"traceutil/trace.go:171","msg":"trace[1953870439] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"333.173595ms","start":"2026-02-17T23:12:49.425382Z","end":"2026-02-17T23:12:49.758555Z","steps":["trace[1953870439] 'process raft request' (duration: 321.730762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:49.425363Z","time spent":"333.209687ms","remote":"127.0.0.1:37378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5246,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:49.75869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.520771ms","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-17T23:12:49.758706Z","caller":"traceutil/trace.go:171","msg":"trace[131305643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"672.557312ms","start":"2026-02-17T23:12:49.086144Z","end":"2026-02-17T23:12:49.758701Z","steps":["trace[131305643] 'agreement among raft nodes before linearized reading' (duration: 672.49363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:49.086131Z","time spent":"672.585283ms","remote":"127.0.0.1:37464","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-17T23:12:49.758847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.507141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:49.75886Z","caller":"traceutil/trace.go:171","msg":"trace[109965300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"399.520901ms","start":"2026-02-17T23:12:49.359335Z","end":"2026-02-17T23:12:49.758856Z","steps":["trace[109965300] 'agreement among raft nodes before linearized reading' (duration: 399.49833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:49.758871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:49.359279Z","time spent":"399.589375ms","remote":"127.0.0.1:37186","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-17T23:12:50.188097Z","caller":"traceutil/trace.go:171","msg":"trace[156450818] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"103.724049ms","start":"2026-02-17T23:12:50.084358Z","end":"2026-02-17T23:12:50.188082Z","steps":["trace[156450818] 'process raft request' (duration: 60.114493ms)","trace[156450818] 'compare' (duration: 43.325603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:51.490414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130746013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:51.4905Z","caller":"traceutil/trace.go:171","msg":"trace[1592070917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"1.130865948s","start":"2026-02-17T23:12:50.359612Z","end":"2026-02-17T23:12:51.490478Z","steps":["trace[1592070917] 'range keys from in-memory index tree' (duration: 1.130691791s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:51.490541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:50.359598Z","time spent":"1.130933301s","remote":"127.0.0.1:37186","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-17T23:12:51.503967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.479374ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T23:12:55.992556Z","caller":"traceutil/trace.go:171","msg":"trace[1487292613] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"159.158737ms","start":"2026-02-17T23:12:55.833383Z","end":"2026-02-17T23:12:55.992542Z","steps":["trace[1487292613] 'read index received' (duration: 32.336402ms)","trace[1487292613] 'applied index is now lower than readState.Index' (duration: 126.821835ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:55.992685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.290142ms","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-17T23:12:55.992702Z","caller":"traceutil/trace.go:171","msg":"trace[901202980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"159.370526ms","start":"2026-02-17T23:12:55.833326Z","end":"2026-02-17T23:12:55.992697Z","steps":["trace[901202980] 'agreement among raft nodes before linearized reading' (duration: 159.267811ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:55.992906Z","caller":"traceutil/trace.go:171","msg":"trace[129327305] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"177.592086ms","start":"2026-02-17T23:12:55.815308Z","end":"2026-02-17T23:12:55.9929Z","steps":["trace[129327305] 'process raft request' (duration: 177.142457ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:55.992986Z","caller":"traceutil/trace.go:171","msg":"trace[1073876996] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"177.650079ms","start":"2026-02-17T23:12:55.815331Z","end":"2026-02-17T23:12:55.992981Z","steps":["trace[1073876996] 'process raft request' (duration: 177.191089ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:57.899816Z","caller":"traceutil/trace.go:171","msg":"trace[1115126364] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"128.726168ms","start":"2026-02-17T23:12:57.771075Z","end":"2026-02-17T23:12:57.899801Z","steps":["trace[1115126364] 'process raft request' (duration: 68.544122ms)","trace[1115126364] 'compare' (duration: 59.8056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:13:00.553462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.089518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:13:00.553532Z","caller":"traceutil/trace.go:171","msg":"trace[347056505] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"642.416198ms","start":"2026-02-17T23:12:59.911104Z","end":"2026-02-17T23:13:00.55352Z","steps":["trace[347056505] 'read index received' (duration: 105.191813ms)","trace[347056505] 'applied index is now lower than readState.Index' (duration: 537.223695ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:13:00.553652Z","caller":"traceutil/trace.go:171","msg":"trace[191875042] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"646.578521ms","start":"2026-02-17T23:12:59.907066Z","end":"2026-02-17T23:13:00.553644Z","steps":["trace[191875042] 'process raft request' (duration: 109.248992ms)","trace[191875042] 'compare' (duration: 536.89331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:13:00.553692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:59.90705Z","time spent":"646.620032ms","remote":"127.0.0.1:37464","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-17T23:13:00.553858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.750772ms","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-17T23:13:00.553877Z","caller":"traceutil/trace.go:171","msg":"trace[1054606883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"642.781493ms","start":"2026-02-17T23:12:59.911089Z","end":"2026-02-17T23:13:00.55387Z","steps":["trace[1054606883] 'agreement among raft nodes before linearized reading' (duration: 642.70912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:00.553895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:59.911083Z","time spent":"642.808445ms","remote":"127.0.0.1:37464","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-17T23:13:01.054511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697344297724352,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T23:13:01.431575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.473755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:13:01.432793Z","caller":"traceutil/trace.go:171","msg":"trace[1351869653] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.050020124s","start":"2026-02-17T23:13:00.382751Z","end":"2026-02-17T23:13:01.432771Z","steps":["trace[1351869653] 'process raft request' (duration: 865.289204ms)","trace[1351869653] 'compare' (duration: 183.268697ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:13:01.433171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.382727Z","time spent":"1.050340328s","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:13:01.43376Z","caller":"traceutil/trace.go:171","msg":"trace[1412176288] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"852.251481ms","start":"2026-02-17T23:13:00.581499Z","end":"2026-02-17T23:13:01.433751Z","steps":["trace[1412176288] 'process raft request' (duration: 850.302516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.433937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.581477Z","time spent":"852.302853ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:13:01.437916Z","caller":"traceutil/trace.go:171","msg":"trace[785417401] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1048; }","duration":"884.356642ms","start":"2026-02-17T23:13:00.553549Z","end":"2026-02-17T23:13:01.437905Z","steps":["trace[785417401] 'read index received' (duration: 694.501387ms)","trace[785417401] 'applied index is now lower than readState.Index' (duration: 189.853105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:13:01.438143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423820744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:13:01.438286Z","caller":"traceutil/trace.go:171","msg":"trace[2039116111] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1009; }","duration":"1.423953809s","start":"2026-02-17T23:13:00.0143Z","end":"2026-02-17T23:13:01.438254Z","steps":["trace[2039116111] 'agreement among raft nodes before linearized reading' (duration: 1.423813073s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.438364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.014294Z","time spent":"1.424044324s","remote":"127.0.0.1:37330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:13:01.438692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431342395s","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-17T23:13:01.439334Z","caller":"traceutil/trace.go:171","msg":"trace[103435310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"1.431996583s","start":"2026-02-17T23:13:00.00733Z","end":"2026-02-17T23:13:01.439326Z","steps":["trace[103435310] 'agreement among raft nodes before linearized reading' (duration: 1.431296282s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.439429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.007324Z","time spent":"1.432094647s","remote":"127.0.0.1:37464","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-17T23:13:01.438884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433321981s","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-17T23:13:01.438946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438371493s","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-17T23:13:01.438974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.732196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T23:13:01.439164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.058657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-17T23:13:01.441211Z","caller":"traceutil/trace.go:171","msg":"trace[1020570597] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1009; }","duration":"567.125678ms","start":"2026-02-17T23:13:00.874075Z","end":"2026-02-17T23:13:01.4412Z","steps":["trace[1020570597] 'agreement among raft nodes before linearized reading' (duration: 565.056916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.441285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.874061Z","time spent":"567.214191ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-17T23:13:01.444905Z","caller":"traceutil/trace.go:171","msg":"trace[1442376965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"1.444346206s","start":"2026-02-17T23:13:00.000543Z","end":"2026-02-17T23:13:01.444889Z","steps":["trace[1442376965] 'agreement among raft nodes before linearized reading' (duration: 1.438369793s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.444963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.000527Z","time spent":"1.444418539s","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T23:13:01.445402Z","caller":"traceutil/trace.go:171","msg":"trace[1006125075] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1009; }","duration":"787.184751ms","start":"2026-02-17T23:13:00.658206Z","end":"2026-02-17T23:13:01.445391Z","steps":["trace[1006125075] 'agreement among raft nodes before linearized reading' (duration: 780.754827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.445443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.658192Z","time spent":"787.237823ms","remote":"127.0.0.1:37536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-17T23:13:01.441362Z","caller":"traceutil/trace.go:171","msg":"trace[668100115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"1.43581174s","start":"2026-02-17T23:13:00.005542Z","end":"2026-02-17T23:13:01.441354Z","steps":["trace[668100115] 'agreement among raft nodes before linearized reading' (duration: 1.43329493s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:13:01.446093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:13:00.005536Z","time spent":"1.440547539s","remote":"127.0.0.1:37464","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-17T23:16:08.503192Z","caller":"traceutil/trace.go:171","msg":"trace[314397398] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"143.500334ms","start":"2026-02-17T23:16:08.359672Z","end":"2026-02-17T23:16:08.503172Z","steps":["trace[314397398] 'read index received' (duration: 143.37776ms)","trace[314397398] 'applied index is now lower than readState.Index' (duration: 121.934µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:16:08.50337Z","caller":"traceutil/trace.go:171","msg":"trace[1874451901] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"156.175497ms","start":"2026-02-17T23:16:08.347184Z","end":"2026-02-17T23:16:08.503359Z","steps":["trace[1874451901] 'process raft request' (duration: 155.908169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:16:08.504666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.974899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:16:08.504739Z","caller":"traceutil/trace.go:171","msg":"trace[203966847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1796; }","duration":"145.083302ms","start":"2026-02-17T23:16:08.359637Z","end":"2026-02-17T23:16:08.50472Z","steps":["trace[203966847] 'agreement among raft nodes before linearized reading' (duration: 144.963339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:16:09.250024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.65582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:16:09.250136Z","caller":"traceutil/trace.go:171","msg":"trace[768581953] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"612.172862ms","start":"2026-02-17T23:16:08.637946Z","end":"2026-02-17T23:16:09.250119Z","steps":["trace[768581953] 'process raft request' (duration: 242.354127ms)","trace[768581953] 'compare' (duration: 369.253238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:16:09.250191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:16:08.637933Z","time spent":"612.231233ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:16:09.25334Z","caller":"traceutil/trace.go:171","msg":"trace[321279043] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1879; }","duration":"544.770811ms","start":"2026-02-17T23:16:08.708539Z","end":"2026-02-17T23:16:09.253309Z","steps":["trace[321279043] 'read index received' (duration: 171.879574ms)","trace[321279043] 'applied index is now lower than readState.Index' (duration: 372.890317ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:16:09.253703Z","caller":"traceutil/trace.go:171","msg":"trace[2133129114] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"615.057839ms","start":"2026-02-17T23:16:08.638637Z","end":"2026-02-17T23:16:09.253695Z","steps":["trace[2133129114] 'process raft request' (duration: 614.614095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:16:09.253749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:16:08.63863Z","time spent":"615.092659ms","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:16:09.253851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.309787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:16:09.25387Z","caller":"traceutil/trace.go:171","msg":"trace[255139036] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1801; }","duration":"545.355859ms","start":"2026-02-17T23:16:08.708508Z","end":"2026-02-17T23:16:09.253864Z","steps":["trace[255139036] 'agreement among raft nodes before linearized reading' (duration: 545.318838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:16:09.253889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:16:08.708496Z","time spent":"545.387699ms","remote":"127.0.0.1:37670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "}