{"level":"warn","ts":"2026-02-27T20:24:15.642975Z","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-27T20:24:15.643067Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-27T20:24:15.643148Z","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-27T20:24:15.643166Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-02-27T20:24:15.643185Z","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-27T20:24:15.643885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-02-27T20:24:15.643965Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-27T20:24:15.673473Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"29.233779ms"} {"level":"info","ts":"2026-02-27T20:24:15.727135Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-02-27T20:24:15.727321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:24:15.727366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-02-27T20:24:15.727394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:24:15.727448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-02-27T20:24:15.727529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-02-27T20:24:15.752094Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:24:15.757358Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:24:15.760455Z","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-27T20:24:15.771531Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:24:15.77212Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:24:15.772301Z","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-27T20:24:15.77251Z","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-27T20:24:15.772558Z","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-27T20:24:15.778862Z","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-27T20:24:15.779016Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-02-27T20:24:15.779068Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-02-27T20:24:15.781067Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:24:15.781134Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:24:15.784189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-02-27T20:24:15.784307Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-02-27T20:24:16.028544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:24:16.028625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:24:16.028653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-02-27T20:24:16.028672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:24:16.028684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-02-27T20:24:16.028695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-02-27T20:24:16.02871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-02-27T20:24:16.050549Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:24:16.081374Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:24:16.081455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:24:16.081754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:24:16.084816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:24:16.085267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-02-27T20:24:16.086112Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:24:16.086143Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:24:16.087623Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:24:16.087781Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:24:16.087841Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-27T20:24:17.876291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.278535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:17.876417Z","caller":"traceutil/trace.go:171","msg":"trace[1000471370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"120.430897ms","start":"2026-02-27T20:24:17.755964Z","end":"2026-02-27T20:24:17.876395Z","steps":["trace[1000471370] 'agreement among raft nodes before linearized reading' (duration: 120.236004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:18.049243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.700458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-27T20:24:18.049508Z","caller":"traceutil/trace.go:171","msg":"trace[1714415414] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"208.063918ms","start":"2026-02-27T20:24:17.841379Z","end":"2026-02-27T20:24:18.049443Z","steps":["trace[1714415414] 'process raft request' (duration: 81.472881ms)","trace[1714415414] 'compare' (duration: 125.505236ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:18.049746Z","caller":"traceutil/trace.go:171","msg":"trace[733721563] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"207.27213ms","start":"2026-02-27T20:24:17.842466Z","end":"2026-02-27T20:24:18.049738Z","steps":["trace[733721563] 'process raft request' (duration: 207.055697ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.049801Z","caller":"traceutil/trace.go:171","msg":"trace[324661266] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"170.623586ms","start":"2026-02-27T20:24:17.879156Z","end":"2026-02-27T20:24:18.049779Z","steps":["trace[324661266] 'process raft request' (duration: 170.568765ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.049861Z","caller":"traceutil/trace.go:171","msg":"trace[1532893690] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"208.130988ms","start":"2026-02-27T20:24:17.841722Z","end":"2026-02-27T20:24:18.049853Z","steps":["trace[1532893690] 'process raft request' (duration: 207.775044ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.04988Z","caller":"traceutil/trace.go:171","msg":"trace[899190152] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"207.978527ms","start":"2026-02-27T20:24:17.841863Z","end":"2026-02-27T20:24:18.049842Z","steps":["trace[899190152] 'process raft request' (duration: 207.606723ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.050097Z","caller":"traceutil/trace.go:171","msg":"trace[1651115985] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"208.276061ms","start":"2026-02-27T20:24:17.841809Z","end":"2026-02-27T20:24:18.050085Z","steps":["trace[1651115985] 'process raft request' (duration: 207.568893ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.050196Z","caller":"traceutil/trace.go:171","msg":"trace[916208268] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"171.82511ms","start":"2026-02-27T20:24:17.87836Z","end":"2026-02-27T20:24:18.050185Z","steps":["trace[916208268] 'process raft request' (duration: 171.330724ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.050222Z","caller":"traceutil/trace.go:171","msg":"trace[763012333] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"208.355871ms","start":"2026-02-27T20:24:17.841855Z","end":"2026-02-27T20:24:18.050211Z","steps":["trace[763012333] 'process raft request' (duration: 207.587853ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.050272Z","caller":"traceutil/trace.go:171","msg":"trace[807184405] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"202.739943ms","start":"2026-02-27T20:24:17.847523Z","end":"2026-02-27T20:24:18.050263Z","steps":["trace[807184405] 'process raft request' (duration: 202.049155ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.050371Z","caller":"traceutil/trace.go:171","msg":"trace[722421329] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:16; }","duration":"174.184705ms","start":"2026-02-27T20:24:17.876179Z","end":"2026-02-27T20:24:18.050363Z","steps":["trace[722421329] 'read index received' (duration: 46.516606ms)","trace[722421329] 'applied index is now lower than readState.Index' (duration: 127.667099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:18.050512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.274691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:18.050547Z","caller":"traceutil/trace.go:171","msg":"trace[827267611] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:21; }","duration":"209.345701ms","start":"2026-02-27T20:24:17.841189Z","end":"2026-02-27T20:24:18.050535Z","steps":["trace[827267611] 'agreement among raft nodes before linearized reading' (duration: 209.273011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:18.050706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.469002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-02-27T20:24:18.050735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.703978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:18.050756Z","caller":"traceutil/trace.go:171","msg":"trace[804078679] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"124.767269ms","start":"2026-02-27T20:24:17.925983Z","end":"2026-02-27T20:24:18.05075Z","steps":["trace[804078679] 'agreement among raft nodes before linearized reading' (duration: 124.728018ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:18.050771Z","caller":"traceutil/trace.go:171","msg":"trace[698533603] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"210.564454ms","start":"2026-02-27T20:24:17.840179Z","end":"2026-02-27T20:24:18.050744Z","steps":["trace[698533603] 'agreement among raft nodes before linearized reading' (duration: 210.463293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:18.434773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.051071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-27T20:24:18.434905Z","caller":"traceutil/trace.go:171","msg":"trace[899740149] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"377.981064ms","start":"2026-02-27T20:24:18.056901Z","end":"2026-02-27T20:24:18.434882Z","steps":["trace[899740149] 'process raft request' (duration: 95.719751ms)","trace[899740149] 'compare' (duration: 281.882179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:18.434979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.056881Z","time spent":"378.068285ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:19.622435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.46977472s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:24:20.252763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.003894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-27T20:24:20.253008Z","caller":"traceutil/trace.go:171","msg":"trace[1061177652] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"840.965151ms","start":"2026-02-27T20:24:19.412032Z","end":"2026-02-27T20:24:20.252997Z","steps":["trace[1061177652] 'process raft request' (duration: 840.94176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:19.411984Z","time spent":"841.053791ms","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:24:20.253085Z","caller":"traceutil/trace.go:171","msg":"trace[1073041084] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"2.195653025s","start":"2026-02-27T20:24:18.057385Z","end":"2026-02-27T20:24:20.253038Z","steps":["trace[1073041084] 'process raft request' (duration: 2.195500944s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.253122Z","caller":"traceutil/trace.go:171","msg":"trace[1724871783] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"2.195373683s","start":"2026-02-27T20:24:18.05771Z","end":"2026-02-27T20:24:20.253084Z","steps":["trace[1724871783] 'process raft request' (duration: 2.195223541s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.253157Z","caller":"traceutil/trace.go:171","msg":"trace[929602310] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:25; }","duration":"2.195829997s","start":"2026-02-27T20:24:18.057296Z","end":"2026-02-27T20:24:20.253125Z","steps":["trace[929602310] 'read index received' (duration: 95.337167ms)","trace[929602310] 'applied index is now lower than readState.Index' (duration: 2.1004899s)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:20.253214Z","caller":"traceutil/trace.go:171","msg":"trace[1890036280] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"2.193450742s","start":"2026-02-27T20:24:18.059704Z","end":"2026-02-27T20:24:20.253155Z","steps":["trace[1890036280] 'process raft request' (duration: 2.19324669s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.057379Z","time spent":"2.195780226s","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:20.253269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.05768Z","time spent":"2.195514344s","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:20.253309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.059684Z","time spent":"2.193580413s","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:24:20.253332Z","caller":"traceutil/trace.go:171","msg":"trace[1920293301] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"2.196268201s","start":"2026-02-27T20:24:18.057055Z","end":"2026-02-27T20:24:20.253323Z","steps":["trace[1920293301] 'process raft request' (duration: 1.565637561s)","trace[1920293301] 'compare' (duration: 629.902393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:20.253359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.057033Z","time spent":"2.196312221s","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:20.253357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.196040729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:20.253395Z","caller":"traceutil/trace.go:171","msg":"trace[468632188] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:30; }","duration":"2.19611903s","start":"2026-02-27T20:24:18.057265Z","end":"2026-02-27T20:24:20.253384Z","steps":["trace[468632188] 'agreement among raft nodes before linearized reading' (duration: 2.196010608s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.25341Z","caller":"traceutil/trace.go:171","msg":"trace[1849747503] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"2.19614636s","start":"2026-02-27T20:24:18.057252Z","end":"2026-02-27T20:24:20.253399Z","steps":["trace[1849747503] 'process raft request' (duration: 2.195589154s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.057257Z","time spent":"2.19616337s","remote":"127.0.0.1:48442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-02-27T20:24:20.253493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.057243Z","time spent":"2.19619099s","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:24:20.253506Z","caller":"traceutil/trace.go:171","msg":"trace[95362342] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"2.19608465s","start":"2026-02-27T20:24:18.057417Z","end":"2026-02-27T20:24:20.253501Z","steps":["trace[95362342] 'process raft request' (duration: 2.195488054s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.057406Z","time spent":"2.19611587s","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:20.253545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718727516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:20.25358Z","caller":"traceutil/trace.go:171","msg":"trace[1275751950] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:30; }","duration":"1.718829227s","start":"2026-02-27T20:24:18.534738Z","end":"2026-02-27T20:24:20.253567Z","steps":["trace[1275751950] 'agreement among raft nodes before linearized reading' (duration: 1.718769837s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.534708Z","time spent":"1.718893728s","remote":"127.0.0.1:48482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-02-27T20:24:20.253632Z","caller":"traceutil/trace.go:171","msg":"trace[868593294] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"2.19616438s","start":"2026-02-27T20:24:18.057464Z","end":"2026-02-27T20:24:20.253628Z","steps":["trace[868593294] 'process raft request' (duration: 2.195454433s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.057459Z","time spent":"2.1961837s","remote":"127.0.0.1:48874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:20.253703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605449504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:20.253735Z","caller":"traceutil/trace.go:171","msg":"trace[290190587] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:30; }","duration":"1.605526405s","start":"2026-02-27T20:24:18.6482Z","end":"2026-02-27T20:24:20.253726Z","steps":["trace[290190587] 'agreement among raft nodes before linearized reading' (duration: 1.605476214s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605556595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:20.25378Z","caller":"traceutil/trace.go:171","msg":"trace[1004067798] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:30; }","duration":"1.605630485s","start":"2026-02-27T20:24:18.648141Z","end":"2026-02-27T20:24:20.253771Z","steps":["trace[1004067798] 'agreement among raft nodes before linearized reading' (duration: 1.605583035s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.253745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573973769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T20:24:20.25376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.64818Z","time spent":"1.605574315s","remote":"127.0.0.1:48696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-27T20:24:20.253807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.648116Z","time spent":"1.605684126s","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-27T20:24:20.253778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.351074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:24:20.253847Z","caller":"traceutil/trace.go:171","msg":"trace[2093961479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:30; }","duration":"1.57408968s","start":"2026-02-27T20:24:18.679725Z","end":"2026-02-27T20:24:20.253815Z","steps":["trace[2093961479] 'agreement among raft nodes before linearized reading' (duration: 1.573908888s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.253863Z","caller":"traceutil/trace.go:171","msg":"trace[730487069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:30; }","duration":"512.479036ms","start":"2026-02-27T20:24:19.741371Z","end":"2026-02-27T20:24:20.25385Z","steps":["trace[730487069] 'agreement among raft nodes before linearized reading' (duration: 512.357575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:20.25391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:19.741349Z","time spent":"512.553217ms","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:24:20.253909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:18.679686Z","time spent":"1.574213651s","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:24:20.377217Z","caller":"traceutil/trace.go:171","msg":"trace[512978477] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"105.440055ms","start":"2026-02-27T20:24:20.271738Z","end":"2026-02-27T20:24:20.377178Z","steps":["trace[512978477] 'process raft request' (duration: 105.247513ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.377434Z","caller":"traceutil/trace.go:171","msg":"trace[1665218118] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"105.688187ms","start":"2026-02-27T20:24:20.271723Z","end":"2026-02-27T20:24:20.377412Z","steps":["trace[1665218118] 'process raft request' (duration: 105.220302ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.377437Z","caller":"traceutil/trace.go:171","msg":"trace[1827454329] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"105.776058ms","start":"2026-02-27T20:24:20.271605Z","end":"2026-02-27T20:24:20.377381Z","steps":["trace[1827454329] 'process raft request' (duration: 100.446893ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.377442Z","caller":"traceutil/trace.go:171","msg":"trace[1750979226] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"105.685117ms","start":"2026-02-27T20:24:20.271737Z","end":"2026-02-27T20:24:20.377422Z","steps":["trace[1750979226] 'process raft request' (duration: 105.279283ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.377832Z","caller":"traceutil/trace.go:171","msg":"trace[788237437] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"106.053291ms","start":"2026-02-27T20:24:20.271764Z","end":"2026-02-27T20:24:20.377817Z","steps":["trace[788237437] 'process raft request' (duration: 105.294833ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.378265Z","caller":"traceutil/trace.go:171","msg":"trace[67543679] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"106.046961ms","start":"2026-02-27T20:24:20.272202Z","end":"2026-02-27T20:24:20.378248Z","steps":["trace[67543679] 'process raft request' (duration: 104.889969ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.378446Z","caller":"traceutil/trace.go:171","msg":"trace[2027628698] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"106.084472ms","start":"2026-02-27T20:24:20.272346Z","end":"2026-02-27T20:24:20.37843Z","steps":["trace[2027628698] 'process raft request' (duration: 105.819639ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:20.721568Z","caller":"traceutil/trace.go:171","msg":"trace[1573406238] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"107.666167ms","start":"2026-02-27T20:24:20.613874Z","end":"2026-02-27T20:24:20.72154Z","steps":["trace[1573406238] 'process raft request' (duration: 27.712502ms)","trace[1573406238] 'compare' (duration: 78.962315ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:22.289117Z","caller":"traceutil/trace.go:171","msg":"trace[2037654249] linearizableReadLoop","detail":"{readStateIndex:157; appliedIndex:156; }","duration":"156.970327ms","start":"2026-02-27T20:24:22.132113Z","end":"2026-02-27T20:24:22.289083Z","steps":["trace[2037654249] 'read index received' (duration: 62.462217ms)","trace[2037654249] 'applied index is now lower than readState.Index' (duration: 94.50643ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:22.289155Z","caller":"traceutil/trace.go:171","msg":"trace[1696890996] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"164.018248ms","start":"2026-02-27T20:24:22.125074Z","end":"2026-02-27T20:24:22.289092Z","steps":["trace[1696890996] 'process raft request' (duration: 69.602109ms)","trace[1696890996] 'compare' (duration: 94.270838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:22.289335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.210129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:24:22.289387Z","caller":"traceutil/trace.go:171","msg":"trace[702013094] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:0; response_revision:152; }","duration":"157.317ms","start":"2026-02-27T20:24:22.132055Z","end":"2026-02-27T20:24:22.289372Z","steps":["trace[702013094] 'agreement among raft nodes before linearized reading' (duration: 157.191399ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:23.232876Z","caller":"traceutil/trace.go:171","msg":"trace[1470253699] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:203; }","duration":"121.094439ms","start":"2026-02-27T20:24:23.111751Z","end":"2026-02-27T20:24:23.232845Z","steps":["trace[1470253699] 'read index received' (duration: 92.15112ms)","trace[1470253699] 'applied index is now lower than readState.Index' (duration: 28.942129ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:23.233034Z","caller":"traceutil/trace.go:171","msg":"trace[1590590105] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"190.061167ms","start":"2026-02-27T20:24:23.042934Z","end":"2026-02-27T20:24:23.232996Z","steps":["trace[1590590105] 'process raft request' (duration: 160.947246ms)","trace[1590590105] 'compare' (duration: 28.845748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:23.233174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.409452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:24:23.233252Z","caller":"traceutil/trace.go:171","msg":"trace[1901849155] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:0; response_revision:199; }","duration":"121.562913ms","start":"2026-02-27T20:24:23.11167Z","end":"2026-02-27T20:24:23.233233Z","steps":["trace[1901849155] 'agreement among raft nodes before linearized reading' (duration: 121.363001ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:23.840687Z","caller":"traceutil/trace.go:171","msg":"trace[1102108013] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"125.141989ms","start":"2026-02-27T20:24:23.715511Z","end":"2026-02-27T20:24:23.840653Z","steps":["trace[1102108013] 'process raft request' (duration: 86.911137ms)","trace[1102108013] 'compare' (duration: 38.04961ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:25.018025Z","caller":"traceutil/trace.go:171","msg":"trace[619731222] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"120.720335ms","start":"2026-02-27T20:24:24.897279Z","end":"2026-02-27T20:24:25.018Z","steps":["trace[619731222] 'process raft request' (duration: 25.012768ms)","trace[619731222] 'compare' (duration: 95.528895ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:31.332765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.926118ms","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-27T20:24:31.33288Z","caller":"traceutil/trace.go:171","msg":"trace[1522116262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:383; }","duration":"256.118059ms","start":"2026-02-27T20:24:31.076735Z","end":"2026-02-27T20:24:31.332853Z","steps":["trace[1522116262] 'range keys from in-memory index tree' (duration: 255.679835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:31.3329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.226727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-27T20:24:31.333029Z","caller":"traceutil/trace.go:171","msg":"trace[197518072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:383; }","duration":"954.463989ms","start":"2026-02-27T20:24:30.378543Z","end":"2026-02-27T20:24:31.333007Z","steps":["trace[197518072] 'range keys from in-memory index tree' (duration: 954.083284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:31.333014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.507266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-27T20:24:31.333083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:30.378519Z","time spent":"954.55398ms","remote":"127.0.0.1:48544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-27T20:24:31.333143Z","caller":"traceutil/trace.go:171","msg":"trace[70119864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:383; }","duration":"289.740368ms","start":"2026-02-27T20:24:31.043373Z","end":"2026-02-27T20:24:31.333114Z","steps":["trace[70119864] 'range keys from in-memory index tree' (duration: 289.313954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:31.333503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.762646ms","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-27T20:24:31.333904Z","caller":"traceutil/trace.go:171","msg":"trace[1461304791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:383; }","duration":"399.906226ms","start":"2026-02-27T20:24:30.933964Z","end":"2026-02-27T20:24:31.333871Z","steps":["trace[1461304791] 'range keys from in-memory index tree' (duration: 398.630695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:31.334105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:30.933937Z","time spent":"400.027248ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:24:37.800743Z","caller":"traceutil/trace.go:171","msg":"trace[817076390] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"385.746706ms","start":"2026-02-27T20:24:37.414957Z","end":"2026-02-27T20:24:37.800703Z","steps":["trace[817076390] 'read index received' (duration: 385.591585ms)","trace[817076390] 'applied index is now lower than readState.Index' (duration: 154.221µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:37.800791Z","caller":"traceutil/trace.go:171","msg":"trace[936104827] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"410.23551ms","start":"2026-02-27T20:24:37.390516Z","end":"2026-02-27T20:24:37.800751Z","steps":["trace[936104827] 'process raft request' (duration: 410.075319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:37.801054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.955568ms","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-27T20:24:37.801079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.064049ms","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-27T20:24:37.801139Z","caller":"traceutil/trace.go:171","msg":"trace[1840693703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"386.11982ms","start":"2026-02-27T20:24:37.415002Z","end":"2026-02-27T20:24:37.801122Z","steps":["trace[1840693703] 'agreement among raft nodes before linearized reading' (duration: 385.872607ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:37.801226Z","caller":"traceutil/trace.go:171","msg":"trace[86940519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"386.287351ms","start":"2026-02-27T20:24:37.41491Z","end":"2026-02-27T20:24:37.801198Z","steps":["trace[86940519] 'agreement among raft nodes before linearized reading' (duration: 385.962868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:37.801219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:37.414979Z","time spent":"386.2289ms","remote":"127.0.0.1:48596","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-27T20:24:37.801304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:37.414896Z","time spent":"386.390332ms","remote":"127.0.0.1:48596","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-27T20:24:37.803573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:37.390493Z","time spent":"410.392292ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:24:39.002726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.097152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:24:39.002939Z","caller":"traceutil/trace.go:171","msg":"trace[350922789] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:432; }","duration":"830.342206ms","start":"2026-02-27T20:24:38.17258Z","end":"2026-02-27T20:24:39.002922Z","steps":["trace[350922789] 'read index received' (duration: 230.69876ms)","trace[350922789] 'applied index is now lower than readState.Index' (duration: 599.642066ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:39.003052Z","caller":"traceutil/trace.go:171","msg":"trace[1098361833] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"1.196568805s","start":"2026-02-27T20:24:37.80647Z","end":"2026-02-27T20:24:39.003039Z","steps":["trace[1098361833] 'process raft request' (duration: 1.196377883s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:39.002939Z","caller":"traceutil/trace.go:171","msg":"trace[970640762] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.197337242s","start":"2026-02-27T20:24:37.805579Z","end":"2026-02-27T20:24:39.002916Z","steps":["trace[970640762] 'process raft request' (duration: 597.679146ms)","trace[970640762] 'compare' (duration: 598.409915ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:39.003137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:37.805556Z","time spent":"1.197538443s","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:24:39.003186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.377244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-27T20:24:39.003215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.640408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:24:39.003141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:37.806458Z","time spent":"1.196636085s","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:24:39.003259Z","caller":"traceutil/trace.go:171","msg":"trace[562311710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"830.719119ms","start":"2026-02-27T20:24:38.172527Z","end":"2026-02-27T20:24:39.003246Z","steps":["trace[562311710] 'agreement among raft nodes before linearized reading' (duration: 830.658438ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:39.003246Z","caller":"traceutil/trace.go:171","msg":"trace[1992813004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"751.484655ms","start":"2026-02-27T20:24:38.251745Z","end":"2026-02-27T20:24:39.00323Z","steps":["trace[1992813004] 'agreement among raft nodes before linearized reading' (duration: 751.379594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:39.0033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:38.172505Z","time spent":"830.78577ms","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:24:39.00331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:38.251722Z","time spent":"751.575605ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:24:44.263629Z","caller":"traceutil/trace.go:171","msg":"trace[917454086] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"428.673966ms","start":"2026-02-27T20:24:43.834909Z","end":"2026-02-27T20:24:44.263583Z","steps":["trace[917454086] 'process raft request' (duration: 428.116491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:44.263842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:43.834874Z","time spent":"428.869668ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:24:49.211054Z","caller":"traceutil/trace.go:171","msg":"trace[1712285790] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"145.587938ms","start":"2026-02-27T20:24:49.065436Z","end":"2026-02-27T20:24:49.211024Z","steps":["trace[1712285790] 'read index received' (duration: 145.407205ms)","trace[1712285790] 'applied index is now lower than readState.Index' (duration: 179.383µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:24:49.211185Z","caller":"traceutil/trace.go:171","msg":"trace[1538229304] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"147.248873ms","start":"2026-02-27T20:24:49.063905Z","end":"2026-02-27T20:24:49.211153Z","steps":["trace[1538229304] 'process raft request' (duration: 146.934757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:49.211257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.791462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T20:24:49.211313Z","caller":"traceutil/trace.go:171","msg":"trace[591227457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"145.880075ms","start":"2026-02-27T20:24:49.06541Z","end":"2026-02-27T20:24:49.21129Z","steps":["trace[591227457] 'agreement among raft nodes before linearized reading' (duration: 145.768703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:49.211484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.933766ms","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-27T20:24:49.211551Z","caller":"traceutil/trace.go:171","msg":"trace[1756565834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"146.11365ms","start":"2026-02-27T20:24:49.06542Z","end":"2026-02-27T20:24:49.211533Z","steps":["trace[1756565834] 'agreement among raft nodes before linearized reading' (duration: 145.913166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:58.672757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224486074217388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:24:59.173686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224486074217388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:24:59.581439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.678998399s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:24:59.581793Z","caller":"traceutil/trace.go:171","msg":"trace[1557760453] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.679511549s","start":"2026-02-27T20:24:57.902257Z","end":"2026-02-27T20:24:59.581769Z","steps":["trace[1557760453] 'process raft request' (duration: 1.679399417s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.581905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:57.902228Z","time spent":"1.67962606s","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:24:59.597511Z","caller":"traceutil/trace.go:171","msg":"trace[1675822431] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.496506206s","start":"2026-02-27T20:24:58.10098Z","end":"2026-02-27T20:24:59.597486Z","steps":["trace[1675822431] 'process raft request' (duration: 1.496388293s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.597625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.100945Z","time spent":"1.496628027s","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:24:59.597643Z","caller":"traceutil/trace.go:171","msg":"trace[1641872701] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"892.21019ms","start":"2026-02-27T20:24:58.705405Z","end":"2026-02-27T20:24:59.597615Z","steps":["trace[1641872701] 'process raft request' (duration: 892.028887ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:59.597723Z","caller":"traceutil/trace.go:171","msg":"trace[41839016] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"695.017182ms","start":"2026-02-27T20:24:58.902639Z","end":"2026-02-27T20:24:59.597657Z","steps":["trace[41839016] 'process raft request' (duration: 694.828688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.597774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.705377Z","time spent":"892.335963ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:24:59.597934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.902594Z","time spent":"695.233026ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:24:59.604637Z","caller":"traceutil/trace.go:171","msg":"trace[1243979452] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:571; }","duration":"1.432487308s","start":"2026-02-27T20:24:58.172106Z","end":"2026-02-27T20:24:59.604593Z","steps":["trace[1243979452] 'read index received' (duration: 1.409581763s)","trace[1243979452] 'applied index is now lower than readState.Index' (duration: 22.904175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:24:59.604803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432684292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:24:59.604809Z","caller":"traceutil/trace.go:171","msg":"trace[1309804680] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"207.084755ms","start":"2026-02-27T20:24:59.397675Z","end":"2026-02-27T20:24:59.60476Z","steps":["trace[1309804680] 'process raft request' (duration: 206.542625ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:24:59.604827Z","caller":"traceutil/trace.go:171","msg":"trace[1420045398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"1.432778433s","start":"2026-02-27T20:24:58.172041Z","end":"2026-02-27T20:24:59.604819Z","steps":["trace[1420045398] 'agreement among raft nodes before linearized reading' (duration: 1.432696362s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.604895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.172013Z","time spent":"1.432876275s","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.532313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T20:24:59.60783Z","caller":"traceutil/trace.go:171","msg":"trace[1769647176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"338.132384ms","start":"2026-02-27T20:24:59.26969Z","end":"2026-02-27T20:24:59.607823Z","steps":["trace[1769647176] 'agreement among raft nodes before linearized reading' (duration: 337.496492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.60786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:59.269668Z","time spent":"338.185475ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.920487ms","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-27T20:24:59.607932Z","caller":"traceutil/trace.go:171","msg":"trace[1000166480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"322.63602ms","start":"2026-02-27T20:24:59.285282Z","end":"2026-02-27T20:24:59.607918Z","steps":["trace[1000166480] 'agreement among raft nodes before linearized reading' (duration: 321.815775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.607974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:59.285258Z","time spent":"322.706602ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283106456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T20:24:59.60772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307018714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:24:59.608193Z","caller":"traceutil/trace.go:171","msg":"trace[1784551913] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"1.307488304s","start":"2026-02-27T20:24:58.300684Z","end":"2026-02-27T20:24:59.608172Z","steps":["trace[1784551913] 'agreement among raft nodes before linearized reading' (duration: 1.306997564s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.608245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.300622Z","time spent":"1.307608796s","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-27T20:24:59.608066Z","caller":"traceutil/trace.go:171","msg":"trace[2101719905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"1.283828321s","start":"2026-02-27T20:24:58.324224Z","end":"2026-02-27T20:24:59.608052Z","steps":["trace[2101719905] 'agreement among raft nodes before linearized reading' (duration: 1.283114066s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.608342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.324201Z","time spent":"1.284132476s","remote":"127.0.0.1:48596","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-27T20:24:59.607596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.162197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-27T20:24:59.608516Z","caller":"traceutil/trace.go:171","msg":"trace[455132792] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:562; }","duration":"508.147096ms","start":"2026-02-27T20:24:59.100358Z","end":"2026-02-27T20:24:59.608505Z","steps":["trace[455132792] 'agreement among raft nodes before linearized reading' (duration: 507.083355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.608551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:59.100334Z","time spent":"508.208947ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.039769ms","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-27T20:24:59.608676Z","caller":"traceutil/trace.go:171","msg":"trace[1038664183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"323.166501ms","start":"2026-02-27T20:24:59.285503Z","end":"2026-02-27T20:24:59.608669Z","steps":["trace[1038664183] 'agreement among raft nodes before linearized reading' (duration: 321.954017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.608701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:59.285493Z","time spent":"323.202662ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.275297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-27T20:24:59.608769Z","caller":"traceutil/trace.go:171","msg":"trace[1024334719] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:562; }","duration":"309.453481ms","start":"2026-02-27T20:24:59.299302Z","end":"2026-02-27T20:24:59.608756Z","steps":["trace[1024334719] 'agreement among raft nodes before linearized reading' (duration: 308.091334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.608799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:59.29929Z","time spent":"309.501311ms","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.322741ms","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-27T20:24:59.608888Z","caller":"traceutil/trace.go:171","msg":"trace[1601122510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:562; }","duration":"497.560865ms","start":"2026-02-27T20:24:59.111319Z","end":"2026-02-27T20:24:59.60888Z","steps":["trace[1601122510] 'agreement among raft nodes before linearized reading' (duration: 496.306731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.608913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:59.111304Z","time spent":"497.602996ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:24:59.607726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.92227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-27T20:24:59.609404Z","caller":"traceutil/trace.go:171","msg":"trace[1318154230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:562; }","duration":"109.623593ms","start":"2026-02-27T20:24:59.499767Z","end":"2026-02-27T20:24:59.609391Z","steps":["trace[1318154230] 'agreement among raft nodes before linearized reading' (duration: 107.893049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.607462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107701205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:24:59.60961Z","caller":"traceutil/trace.go:171","msg":"trace[2141496109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"1.109895838s","start":"2026-02-27T20:24:58.499706Z","end":"2026-02-27T20:24:59.609602Z","steps":["trace[2141496109] 'agreement among raft nodes before linearized reading' (duration: 1.107740506s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:24:59.609677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:24:58.499675Z","time spent":"1.109985909s","remote":"127.0.0.1:48406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-27T20:25:04.663874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:04.284327Z","time spent":"379.540991ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-27T20:25:06.213974Z","caller":"traceutil/trace.go:171","msg":"trace[1738284016] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"533.120772ms","start":"2026-02-27T20:25:05.680827Z","end":"2026-02-27T20:25:06.213948Z","steps":["trace[1738284016] 'process raft request' (duration: 532.966069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:06.214128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:05.680814Z","time spent":"533.240534ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:25:06.494881Z","caller":"traceutil/trace.go:171","msg":"trace[145337739] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"805.953167ms","start":"2026-02-27T20:25:05.688869Z","end":"2026-02-27T20:25:06.494822Z","steps":["trace[145337739] 'process raft request' (duration: 805.699182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:06.495118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:05.688855Z","time spent":"806.13893ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:25:06.495132Z","caller":"traceutil/trace.go:171","msg":"trace[2019769645] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"803.95096ms","start":"2026-02-27T20:25:05.691157Z","end":"2026-02-27T20:25:06.495108Z","steps":["trace[2019769645] 'process raft request' (duration: 803.530712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:06.495276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:05.691149Z","time spent":"804.049091ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:25:06.495384Z","caller":"traceutil/trace.go:171","msg":"trace[2120922087] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"803.750556ms","start":"2026-02-27T20:25:05.691625Z","end":"2026-02-27T20:25:06.495376Z","steps":["trace[2120922087] 'process raft request' (duration: 803.100115ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:06.495379Z","caller":"traceutil/trace.go:171","msg":"trace[246820109] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:638; }","duration":"276.972191ms","start":"2026-02-27T20:25:06.218391Z","end":"2026-02-27T20:25:06.495363Z","steps":["trace[246820109] 'read index received' (duration: 276.161357ms)","trace[246820109] 'applied index is now lower than readState.Index' (duration: 809.914µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:25:06.495488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:05.691594Z","time spent":"803.811168ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:25:06.49559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.192855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5qbfj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-27T20:25:06.495622Z","caller":"traceutil/trace.go:171","msg":"trace[1307422099] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5qbfj; range_end:; response_count:1; response_revision:625; }","duration":"277.269176ms","start":"2026-02-27T20:25:06.218343Z","end":"2026-02-27T20:25:06.495613Z","steps":["trace[1307422099] 'agreement among raft nodes before linearized reading' (duration: 277.103033ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:06.495814Z","caller":"traceutil/trace.go:171","msg":"trace[569018702] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"803.330869ms","start":"2026-02-27T20:25:05.692473Z","end":"2026-02-27T20:25:06.495804Z","steps":["trace[569018702] 'process raft request' (duration: 802.856031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:06.495873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:05.692447Z","time spent":"803.38908ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:25:06.762652Z","caller":"traceutil/trace.go:171","msg":"trace[1666294871] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"251.876979ms","start":"2026-02-27T20:25:06.510744Z","end":"2026-02-27T20:25:06.762621Z","steps":["trace[1666294871] 'process raft request' (duration: 251.695824ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:06.936235Z","caller":"traceutil/trace.go:171","msg":"trace[595713102] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"120.232711ms","start":"2026-02-27T20:25:06.815979Z","end":"2026-02-27T20:25:06.936211Z","steps":["trace[595713102] 'process raft request' (duration: 120.037088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:07.837927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.170026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:25:07.838032Z","caller":"traceutil/trace.go:171","msg":"trace[1128258552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"667.344549ms","start":"2026-02-27T20:25:07.170667Z","end":"2026-02-27T20:25:07.838011Z","steps":["trace[1128258552] 'range keys from in-memory index tree' (duration: 666.994673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:07.838069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:07.170638Z","time spent":"667.42235ms","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:25:09.000171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224486074217553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:25:09.044966Z","caller":"traceutil/trace.go:171","msg":"trace[1033549137] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"545.618988ms","start":"2026-02-27T20:25:08.499326Z","end":"2026-02-27T20:25:09.044945Z","steps":["trace[1033549137] 'read index received' (duration: 545.406934ms)","trace[1033549137] 'applied index is now lower than readState.Index' (duration: 211.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:09.045077Z","caller":"traceutil/trace.go:171","msg":"trace[1572550420] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"823.692463ms","start":"2026-02-27T20:25:08.221353Z","end":"2026-02-27T20:25:09.045045Z","steps":["trace[1572550420] 'process raft request' (duration: 823.448249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:09.045252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:08.221342Z","time spent":"823.812355ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:25:10.513039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224486074217557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:25:11.013152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224486074217557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:25:11.071489Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.720190241s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:25:11.130086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.959434033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:25:11.130177Z","caller":"traceutil/trace.go:171","msg":"trace[1730999834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.959570015s","start":"2026-02-27T20:25:09.170579Z","end":"2026-02-27T20:25:11.130149Z","steps":["trace[1730999834] 'range keys from in-memory index tree' (duration: 1.959318951s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:11.130223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:09.170558Z","time spent":"1.959656997s","remote":"127.0.0.1:48358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:25:11.130499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.628999659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-27T20:25:11.130517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.630853732s","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-27T20:25:11.130552Z","caller":"traceutil/trace.go:171","msg":"trace[969095002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.629123721s","start":"2026-02-27T20:25:08.501414Z","end":"2026-02-27T20:25:11.130538Z","steps":["trace[969095002] 'agreement among raft nodes before linearized reading' (duration: 543.717224ms)","trace[969095002] 'range keys from in-memory index tree' (duration: 2.084905709s)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:11.130555Z","caller":"traceutil/trace.go:171","msg":"trace[544637309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.630921682s","start":"2026-02-27T20:25:08.499621Z","end":"2026-02-27T20:25:11.130543Z","steps":["trace[544637309] 'agreement among raft nodes before linearized reading' (duration: 545.483805ms)","trace[544637309] 'range keys from in-memory index tree' (duration: 2.085302355s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:25:11.130582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:08.501385Z","time spent":"2.629189873s","remote":"127.0.0.1:48596","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-27T20:25:11.130585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:08.49961Z","time spent":"2.630967983s","remote":"127.0.0.1:48596","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-27T20:25:11.130575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.631244519s","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-27T20:25:11.130624Z","caller":"traceutil/trace.go:171","msg":"trace[1919813468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"2.631357971s","start":"2026-02-27T20:25:08.499249Z","end":"2026-02-27T20:25:11.130607Z","steps":["trace[1919813468] 'agreement among raft nodes before linearized reading' (duration: 545.786402ms)","trace[1919813468] 'range keys from in-memory index tree' (duration: 2.085462658s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:25:11.130661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:08.499221Z","time spent":"2.631432742s","remote":"127.0.0.1:48596","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-27T20:25:11.131052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.629342595s","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-27T20:25:11.131139Z","caller":"traceutil/trace.go:171","msg":"trace[1852563247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"2.629504868s","start":"2026-02-27T20:25:08.50161Z","end":"2026-02-27T20:25:11.131115Z","steps":["trace[1852563247] 'agreement among raft nodes before linearized reading' (duration: 543.519411ms)","trace[1852563247] 'range keys from in-memory index tree' (duration: 2.084712795s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:25:11.131187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:08.501588Z","time spent":"2.629589259s","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:25:11.153824Z","caller":"traceutil/trace.go:171","msg":"trace[1113504875] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"1.141341165s","start":"2026-02-27T20:25:10.012446Z","end":"2026-02-27T20:25:11.153787Z","steps":["trace[1113504875] 'read index received' (duration: 1.059187373s)","trace[1113504875] 'applied index is now lower than readState.Index' (duration: 82.152582ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:11.153956Z","caller":"traceutil/trace.go:171","msg":"trace[1049908513] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.170298964s","start":"2026-02-27T20:25:09.983645Z","end":"2026-02-27T20:25:11.153944Z","steps":["trace[1049908513] 'process raft request' (duration: 1.169968258s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:11.154089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:09.983617Z","time spent":"1.170374425s","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:25:11.154084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14161923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:25:11.154154Z","caller":"traceutil/trace.go:171","msg":"trace[1637926896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.141739751s","start":"2026-02-27T20:25:10.012399Z","end":"2026-02-27T20:25:11.154139Z","steps":["trace[1637926896] 'agreement among raft nodes before linearized reading' (duration: 1.141613699s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:11.154205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:10.012386Z","time spent":"1.141810093s","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:25:11.184342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.685751ms","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-27T20:25:11.184387Z","caller":"traceutil/trace.go:171","msg":"trace[1234489149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"105.766253ms","start":"2026-02-27T20:25:11.078612Z","end":"2026-02-27T20:25:11.184378Z","steps":["trace[1234489149] 'agreement among raft nodes before linearized reading' (duration: 105.61937ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:41.955724Z","caller":"traceutil/trace.go:171","msg":"trace[531893992] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"195.57613ms","start":"2026-02-27T20:26:41.760111Z","end":"2026-02-27T20:26:41.955687Z","steps":["trace[531893992] 'process raft request' (duration: 156.937767ms)","trace[531893992] 'compare' (duration: 38.261119ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:26:42.146845Z","caller":"traceutil/trace.go:171","msg":"trace[59255576] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"385.802186ms","start":"2026-02-27T20:26:41.761013Z","end":"2026-02-27T20:26:42.146815Z","steps":["trace[59255576] 'process raft request' (duration: 380.664609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:42.147026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:41.760995Z","time spent":"385.930258ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:26:42.147028Z","caller":"traceutil/trace.go:171","msg":"trace[1501444886] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"385.479803ms","start":"2026-02-27T20:26:41.761513Z","end":"2026-02-27T20:26:42.146992Z","steps":["trace[1501444886] 'process raft request' (duration: 385.262221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:42.147239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:41.761507Z","time spent":"385.621264ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:26:43.037862Z","caller":"traceutil/trace.go:171","msg":"trace[1527248718] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"172.742008ms","start":"2026-02-27T20:26:42.865092Z","end":"2026-02-27T20:26:43.037834Z","steps":["trace[1527248718] 'process raft request' (duration: 172.594717ms)"],"step_count":1}