{"level":"warn","ts":"2026-04-01T23:29:24.593929Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:29:24.594084Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-04-01T23:29:24.594216Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:29:24.594238Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-01T23:29:24.594267Z","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-04-01T23:29:24.59525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-01T23:29:24.595375Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-04-01T23:29:24.600223Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.50274ms"} {"level":"info","ts":"2026-04-01T23:29:24.609886Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-01T23:29:24.609999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T23:29:24.610035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-01T23:29:24.610058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T23:29:24.610077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-01T23:29:24.610131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-01T23:29:24.623928Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T23:29:24.625831Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T23:29:24.629025Z","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-04-01T23:29:24.631336Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T23:29:24.631646Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T23:29:24.631892Z","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-04-01T23:29:24.632027Z","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-04-01T23:29:24.632046Z","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-04-01T23:29:24.633759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-01T23:29:24.633981Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-01T23:29:24.637578Z","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-04-01T23:29:24.637742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-01T23:29:24.637826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-01T23:29:24.638041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T23:29:24.638085Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T23:29:25.310883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T23:29:25.310945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T23:29:25.310985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-01T23:29:25.311031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-01T23:29:25.311043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-01T23:29:25.311059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-01T23:29:25.311079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-01T23:29:25.312766Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:29:25.313273Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T23:29:25.31358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:29:25.313685Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:29:25.313778Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:29:25.31381Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:29:25.31385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:29:25.317208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-01T23:29:25.320867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T23:29:25.320967Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T23:29:25.321074Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-01T23:29:26.798891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.865846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T23:29:26.799102Z","caller":"traceutil/trace.go:171","msg":"trace[1922886310] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:8; }","duration":"103.101262ms","start":"2026-04-01T23:29:26.695916Z","end":"2026-04-01T23:29:26.799017Z","steps":["trace[1922886310] 'agreement among raft nodes before linearized reading' (duration: 102.805785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:27.298842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754687103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:29:27.79905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754687103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:29:28.300006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754687103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:29:28.800791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754687103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:29:28.802639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999819145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-01T23:29:28.802754Z","caller":"traceutil/trace.go:171","msg":"trace[2056960463] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999973529s","start":"2026-04-01T23:29:26.802757Z","end":"2026-04-01T23:29:28.80273Z","steps":["trace[2056960463] 'agreement among raft nodes before linearized reading' (duration: 1.999840245s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:28.802818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.80274Z","time spent":"2.00005974s","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/01 23:29:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-01T23:29:29.301331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754687103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:29:29.559451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.761226405s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:29:29.594954Z","caller":"traceutil/trace.go:171","msg":"trace[399924646] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.811794303s","start":"2026-04-01T23:29:26.783135Z","end":"2026-04-01T23:29:29.594929Z","steps":["trace[399924646] 'process raft request' (duration: 2.811739721s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:29.595094Z","caller":"traceutil/trace.go:171","msg":"trace[845542355] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.812051719s","start":"2026-04-01T23:29:26.782985Z","end":"2026-04-01T23:29:29.595037Z","steps":["trace[845542355] 'process raft request' (duration: 2.77675877s)","trace[845542355] 'compare' (duration: 34.932011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:29.595513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.783106Z","time spent":"2.811929796s","remote":"127.0.0.1:55012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:29.595583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.782967Z","time spent":"2.812227493s","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:29.734018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999823106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-01T23:29:29.734153Z","caller":"traceutil/trace.go:171","msg":"trace[1018161353] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000042071s","start":"2026-04-01T23:29:27.734088Z","end":"2026-04-01T23:29:29.73413Z","steps":["trace[1018161353] 'agreement among raft nodes before linearized reading' (duration: 1.999868877s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:29.734219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:27.734058Z","time spent":"2.000145314s","remote":"127.0.0.1:54842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/01 23:29:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-01T23:29:30.807127Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.247311819s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:29:30.810345Z","caller":"traceutil/trace.go:171","msg":"trace[1848915527] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"4.007153861s","start":"2026-04-01T23:29:26.803156Z","end":"2026-04-01T23:29:30.81031Z","steps":["trace[1848915527] 'process raft request' (duration: 4.004248511s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:30.810461Z","caller":"traceutil/trace.go:171","msg":"trace[740224220] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"4.005438719s","start":"2026-04-01T23:29:26.805015Z","end":"2026-04-01T23:29:30.810453Z","steps":["trace[740224220] 'process raft request' (duration: 4.005340957s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:30.810453Z","caller":"traceutil/trace.go:171","msg":"trace[352218579] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"4.005574603s","start":"2026-04-01T23:29:26.804831Z","end":"2026-04-01T23:29:30.810406Z","steps":["trace[352218579] 'process raft request' (duration: 4.005471631s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:30.810507Z","caller":"traceutil/trace.go:171","msg":"trace[484319182] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.202204133s","start":"2026-04-01T23:29:29.608291Z","end":"2026-04-01T23:29:30.810496Z","steps":["trace[484319182] 'process raft request' (duration: 1.202157432s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.81055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.80501Z","time spent":"4.00548329s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:30.810601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.804825Z","time spent":"4.005689966s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:30.810616Z","caller":"traceutil/trace.go:171","msg":"trace[215462108] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"4.00547544s","start":"2026-04-01T23:29:26.805099Z","end":"2026-04-01T23:29:30.810574Z","steps":["trace[215462108] 'process raft request' (duration: 4.005278676s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.810633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.803145Z","time spent":"4.007326365s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:30.810714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.805095Z","time spent":"4.005576953s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:30.810757Z","caller":"traceutil/trace.go:171","msg":"trace[1233745151] linearizableReadLoop","detail":"{readStateIndex:21; appliedIndex:11; }","duration":"4.012244234s","start":"2026-04-01T23:29:26.798506Z","end":"2026-04-01T23:29:30.81075Z","steps":["trace[1233745151] 'read index received' (duration: 2.761253416s)","trace[1233745151] 'applied index is now lower than readState.Index' (duration: 1.250990047s)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:30.810765Z","caller":"traceutil/trace.go:171","msg":"trace[607656360] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"4.005463641s","start":"2026-04-01T23:29:26.805287Z","end":"2026-04-01T23:29:30.81075Z","steps":["trace[607656360] 'process raft request' (duration: 4.005133973s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.8108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.805282Z","time spent":"4.005500231s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:30.810729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:29.608268Z","time spent":"1.202265754s","remote":"127.0.0.1:55012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:30.810912Z","caller":"traceutil/trace.go:171","msg":"trace[120548676] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"4.005648504s","start":"2026-04-01T23:29:26.805258Z","end":"2026-04-01T23:29:30.810906Z","steps":["trace[120548676] 'process raft request' (duration: 4.005137722s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.810944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.805253Z","time spent":"4.005676685s","remote":"127.0.0.1:55306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:30.810989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.049232144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T23:29:30.81102Z","caller":"traceutil/trace.go:171","msg":"trace[1066688182] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:18; }","duration":"4.049306316s","start":"2026-04-01T23:29:26.761705Z","end":"2026-04-01T23:29:30.811012Z","steps":["trace[1066688182] 'agreement among raft nodes before linearized reading' (duration: 4.049262885s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:30.810692Z","caller":"traceutil/trace.go:171","msg":"trace[688271093] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"4.005796718s","start":"2026-04-01T23:29:26.804889Z","end":"2026-04-01T23:29:30.810686Z","steps":["trace[688271093] 'process raft request' (duration: 4.00544901s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.804886Z","time spent":"4.006169187s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:30.810386Z","caller":"traceutil/trace.go:171","msg":"trace[909523144] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"4.005658775s","start":"2026-04-01T23:29:26.804697Z","end":"2026-04-01T23:29:30.810356Z","steps":["trace[909523144] 'process raft request' (duration: 4.005577473s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211470476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-qhh2p\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-01T23:29:30.811187Z","caller":"traceutil/trace.go:171","msg":"trace[997881201] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-qhh2p; range_end:; response_count:1; response_revision:18; }","duration":"1.211549088s","start":"2026-04-01T23:29:29.599631Z","end":"2026-04-01T23:29:30.81118Z","steps":["trace[997881201] 'agreement among raft nodes before linearized reading' (duration: 1.211513647s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:29.599606Z","time spent":"1.211598429s","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-qhh2p\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.805807423s","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-04-01T23:29:30.811242Z","caller":"traceutil/trace.go:171","msg":"trace[1120706387] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:18; }","duration":"3.805867895s","start":"2026-04-01T23:29:27.005368Z","end":"2026-04-01T23:29:30.811236Z","steps":["trace[1120706387] 'agreement among raft nodes before linearized reading' (duration: 3.805820124s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:27.005346Z","time spent":"3.805909446s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.951269576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-01T23:29:30.811335Z","caller":"traceutil/trace.go:171","msg":"trace[159020860] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"3.951338037s","start":"2026-04-01T23:29:26.85999Z","end":"2026-04-01T23:29:30.811328Z","steps":["trace[159020860] 'agreement among raft nodes before linearized reading' (duration: 3.951298386s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.161246777s","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-04-01T23:29:30.811379Z","caller":"traceutil/trace.go:171","msg":"trace[1427015632] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"3.161307068s","start":"2026-04-01T23:29:27.650059Z","end":"2026-04-01T23:29:30.811366Z","steps":["trace[1427015632] 'agreement among raft nodes before linearized reading' (duration: 3.161277398s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.859964Z","time spent":"3.951383709s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:27.650029Z","time spent":"3.16136548s","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.95145527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-01T23:29:30.811464Z","caller":"traceutil/trace.go:171","msg":"trace[1617680187] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"3.951534012s","start":"2026-04-01T23:29:26.859921Z","end":"2026-04-01T23:29:30.811455Z","steps":["trace[1617680187] 'agreement among raft nodes before linearized reading' (duration: 3.951504602s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.859883Z","time spent":"3.951592993s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.998204695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T23:29:30.81155Z","caller":"traceutil/trace.go:171","msg":"trace[420141462] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"3.998221106s","start":"2026-04-01T23:29:26.813323Z","end":"2026-04-01T23:29:30.811545Z","steps":["trace[420141462] 'agreement among raft nodes before linearized reading' (duration: 3.998195005s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.813282Z","time spent":"3.998305738s","remote":"127.0.0.1:55236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.00837029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-01T23:29:30.81169Z","caller":"traceutil/trace.go:171","msg":"trace[1493723096] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"4.009011656s","start":"2026-04-01T23:29:26.802673Z","end":"2026-04-01T23:29:30.811684Z","steps":["trace[1493723096] 'agreement among raft nodes before linearized reading' (duration: 4.008384191s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.80265Z","time spent":"4.009053146s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905551766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-01T23:29:30.811481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.163960562s","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-04-01T23:29:30.811775Z","caller":"traceutil/trace.go:171","msg":"trace[923896518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"1.905608457s","start":"2026-04-01T23:29:28.906162Z","end":"2026-04-01T23:29:30.81177Z","steps":["trace[923896518] 'agreement among raft nodes before linearized reading' (duration: 1.905574306s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:30.811788Z","caller":"traceutil/trace.go:171","msg":"trace[318856653] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"3.16429329s","start":"2026-04-01T23:29:27.647487Z","end":"2026-04-01T23:29:30.81178Z","steps":["trace[318856653] 'agreement among raft nodes before linearized reading' (duration: 3.163974903s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:28.906137Z","time spent":"1.905652918s","remote":"127.0.0.1:54842","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-04-01T23:29:30.81181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:27.647467Z","time spent":"3.164336621s","remote":"127.0.0.1:55192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-01T23:29:30.811162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.80469Z","time spent":"4.006460304s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:30.811133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.119184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-01T23:29:30.811964Z","caller":"traceutil/trace.go:171","msg":"trace[636999257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"578.051126ms","start":"2026-04-01T23:29:30.233907Z","end":"2026-04-01T23:29:30.811958Z","steps":["trace[636999257] 'agreement among raft nodes before linearized reading' (duration: 577.210846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.811988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:30.233874Z","time spent":"578.110227ms","remote":"127.0.0.1:54842","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-04-01T23:29:30.811578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.890917409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-01T23:29:30.811039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:26.761681Z","time spent":"4.049354297s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-01T23:29:30.812289Z","caller":"traceutil/trace.go:171","msg":"trace[465518025] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:18; }","duration":"2.891557454s","start":"2026-04-01T23:29:27.920479Z","end":"2026-04-01T23:29:30.812037Z","steps":["trace[465518025] 'agreement among raft nodes before linearized reading' (duration: 2.890790326s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:30.812479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:27.920451Z","time spent":"2.892013116s","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-01T23:29:33.522851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.656195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:29:33.522965Z","caller":"traceutil/trace.go:171","msg":"trace[1194670010] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"100.53297ms","start":"2026-04-01T23:29:33.422415Z","end":"2026-04-01T23:29:33.522947Z","steps":["trace[1194670010] 'read index received' (duration: 54.271µs)","trace[1194670010] 'applied index is now lower than readState.Index' (duration: 100.477379ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:33.523049Z","caller":"traceutil/trace.go:171","msg":"trace[1789066121] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"126.957516ms","start":"2026-04-01T23:29:33.396027Z","end":"2026-04-01T23:29:33.522985Z","steps":["trace[1789066121] 'compare' (duration: 125.484421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:33.523281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.864399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-01T23:29:33.523329Z","caller":"traceutil/trace.go:171","msg":"trace[221965091] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:272; }","duration":"100.956961ms","start":"2026-04-01T23:29:33.422361Z","end":"2026-04-01T23:29:33.523318Z","steps":["trace[221965091] 'agreement among raft nodes before linearized reading' (duration: 100.659874ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:33.697149Z","caller":"traceutil/trace.go:171","msg":"trace[1766204852] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"169.125882ms","start":"2026-04-01T23:29:33.527993Z","end":"2026-04-01T23:29:33.697119Z","steps":["trace[1766204852] 'process raft request' (duration: 168.970799ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:33.697196Z","caller":"traceutil/trace.go:171","msg":"trace[1253814980] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"169.216394ms","start":"2026-04-01T23:29:33.527947Z","end":"2026-04-01T23:29:33.697163Z","steps":["trace[1253814980] 'process raft request' (duration: 103.497932ms)","trace[1253814980] 'compare' (duration: 65.387654ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:34.162304Z","caller":"traceutil/trace.go:171","msg":"trace[194857713] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"431.747414ms","start":"2026-04-01T23:29:33.730535Z","end":"2026-04-01T23:29:34.162282Z","steps":["trace[194857713] 'process raft request' (duration: 431.57936ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:34.162414Z","caller":"traceutil/trace.go:171","msg":"trace[1589740773] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"411.495817ms","start":"2026-04-01T23:29:33.750885Z","end":"2026-04-01T23:29:34.162381Z","steps":["trace[1589740773] 'process raft request' (duration: 411.323933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:34.162455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:33.730519Z","time spent":"431.864106ms","remote":"127.0.0.1:55028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:34.162546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:33.750871Z","time spent":"411.593759ms","remote":"127.0.0.1:55196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:34.162295Z","caller":"traceutil/trace.go:171","msg":"trace[1957298456] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"459.542013ms","start":"2026-04-01T23:29:33.702713Z","end":"2026-04-01T23:29:34.162255Z","steps":["trace[1957298456] 'process raft request' (duration: 411.976918ms)","trace[1957298456] 'compare' (duration: 47.213837ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:34.162681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.315023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-01T23:29:34.162786Z","caller":"traceutil/trace.go:171","msg":"trace[428560755] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:278; }","duration":"411.406365ms","start":"2026-04-01T23:29:33.751329Z","end":"2026-04-01T23:29:34.162736Z","steps":["trace[428560755] 'agreement among raft nodes before linearized reading' (duration: 411.126228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:34.162748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:33.702697Z","time spent":"459.916722ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:29:34.162825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:33.751321Z","time spent":"411.495317ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-01T23:29:34.162295Z","caller":"traceutil/trace.go:171","msg":"trace[1057352892] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:281; }","duration":"410.931083ms","start":"2026-04-01T23:29:33.751333Z","end":"2026-04-01T23:29:34.162264Z","steps":["trace[1057352892] 'read index received' (duration: 363.375609ms)","trace[1057352892] 'applied index is now lower than readState.Index' (duration: 47.553904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:34.163102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.075461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-01T23:29:34.163165Z","caller":"traceutil/trace.go:171","msg":"trace[1656803278] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:278; }","duration":"373.164584ms","start":"2026-04-01T23:29:33.789982Z","end":"2026-04-01T23:29:34.163147Z","steps":["trace[1656803278] 'agreement among raft nodes before linearized reading' (duration: 373.048621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:34.163215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:33.789945Z","time spent":"373.259356ms","remote":"127.0.0.1:55028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-01T23:29:34.322547Z","caller":"traceutil/trace.go:171","msg":"trace[820477942] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"112.049218ms","start":"2026-04-01T23:29:34.210468Z","end":"2026-04-01T23:29:34.322517Z","steps":["trace[820477942] 'process raft request' (duration: 111.979756ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:34.322574Z","caller":"traceutil/trace.go:171","msg":"trace[386526779] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"137.721286ms","start":"2026-04-01T23:29:34.184806Z","end":"2026-04-01T23:29:34.322527Z","steps":["trace[386526779] 'process raft request' (duration: 105.317046ms)","trace[386526779] 'compare' (duration: 32.129313ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:34.322812Z","caller":"traceutil/trace.go:171","msg":"trace[1058522630] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"113.768029ms","start":"2026-04-01T23:29:34.208993Z","end":"2026-04-01T23:29:34.322761Z","steps":["trace[1058522630] 'read index received' (duration: 81.069061ms)","trace[1058522630] 'applied index is now lower than readState.Index' (duration: 32.696238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:34.323068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.047905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-01T23:29:34.323115Z","caller":"traceutil/trace.go:171","msg":"trace[1309486084] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:282; }","duration":"114.155458ms","start":"2026-04-01T23:29:34.208948Z","end":"2026-04-01T23:29:34.323103Z","steps":["trace[1309486084] 'agreement among raft nodes before linearized reading' (duration: 113.917372ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:57.306536Z","caller":"traceutil/trace.go:171","msg":"trace[1777969529] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"184.481821ms","start":"2026-04-01T23:29:57.122017Z","end":"2026-04-01T23:29:57.306498Z","steps":["trace[1777969529] 'process raft request' (duration: 126.801313ms)","trace[1777969529] 'compare' (duration: 57.264738ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:57.30662Z","caller":"traceutil/trace.go:171","msg":"trace[1988706289] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"184.512242ms","start":"2026-04-01T23:29:57.122097Z","end":"2026-04-01T23:29:57.306609Z","steps":["trace[1988706289] 'process raft request' (duration: 184.260726ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:57.306541Z","caller":"traceutil/trace.go:171","msg":"trace[538931342] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"182.963084ms","start":"2026-04-01T23:29:57.123548Z","end":"2026-04-01T23:29:57.306511Z","steps":["trace[538931342] 'process raft request' (duration: 182.863462ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:57.306987Z","caller":"traceutil/trace.go:171","msg":"trace[1777655088] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:511; }","duration":"182.161075ms","start":"2026-04-01T23:29:57.124792Z","end":"2026-04-01T23:29:57.306954Z","steps":["trace[1777655088] 'read index received' (duration: 124.110528ms)","trace[1777655088] 'applied index is now lower than readState.Index' (duration: 58.047417ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:57.307128Z","caller":"traceutil/trace.go:171","msg":"trace[296107133] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"183.294733ms","start":"2026-04-01T23:29:57.123817Z","end":"2026-04-01T23:29:57.307111Z","steps":["trace[296107133] 'process raft request' (duration: 182.651417ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:57.307416Z","caller":"traceutil/trace.go:171","msg":"trace[844108828] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"178.114568ms","start":"2026-04-01T23:29:57.129291Z","end":"2026-04-01T23:29:57.307405Z","steps":["trace[844108828] 'process raft request' (duration: 177.410011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:57.30997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.149567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-01T23:29:57.31003Z","caller":"traceutil/trace.go:171","msg":"trace[1534713640] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:498; }","duration":"185.25049ms","start":"2026-04-01T23:29:57.124763Z","end":"2026-04-01T23:29:57.310013Z","steps":["trace[1534713640] 'agreement among raft nodes before linearized reading' (duration: 182.311879ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:57.310435Z","caller":"traceutil/trace.go:171","msg":"trace[1611927522] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"129.244302ms","start":"2026-04-01T23:29:57.181155Z","end":"2026-04-01T23:29:57.310399Z","steps":["trace[1611927522] 'process raft request' (duration: 128.72254ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:59.460883Z","caller":"traceutil/trace.go:171","msg":"trace[1975774604] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"606.373478ms","start":"2026-04-01T23:29:58.854476Z","end":"2026-04-01T23:29:59.46085Z","steps":["trace[1975774604] 'process raft request' (duration: 606.213884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.461077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.854453Z","time spent":"606.538863ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.468893Z","caller":"traceutil/trace.go:171","msg":"trace[8778682] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"603.880938ms","start":"2026-04-01T23:29:58.864972Z","end":"2026-04-01T23:29:59.468853Z","steps":["trace[8778682] 'process raft request' (duration: 603.675983ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:59.468968Z","caller":"traceutil/trace.go:171","msg":"trace[2064172662] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"603.684314ms","start":"2026-04-01T23:29:58.865236Z","end":"2026-04-01T23:29:59.46892Z","steps":["trace[2064172662] 'process raft request' (duration: 603.5301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.469175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.864957Z","time spent":"604.024892ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:59.469174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.865135Z","time spent":"603.935999ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.469226Z","caller":"traceutil/trace.go:171","msg":"trace[1443014616] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"594.450341ms","start":"2026-04-01T23:29:58.87473Z","end":"2026-04-01T23:29:59.46918Z","steps":["trace[1443014616] 'process raft request' (duration: 594.137173ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:59.469343Z","caller":"traceutil/trace.go:171","msg":"trace[916533548] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"593.671152ms","start":"2026-04-01T23:29:58.875654Z","end":"2026-04-01T23:29:59.469325Z","steps":["trace[916533548] 'process raft request' (duration: 593.279783ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:59.46939Z","caller":"traceutil/trace.go:171","msg":"trace[668685240] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"594.81271ms","start":"2026-04-01T23:29:58.87457Z","end":"2026-04-01T23:29:59.469383Z","steps":["trace[668685240] 'process raft request' (duration: 594.267627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.469423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.874565Z","time spent":"594.84166ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:59.469428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.875648Z","time spent":"593.751524ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.469455Z","caller":"traceutil/trace.go:171","msg":"trace[1325283278] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"594.942373ms","start":"2026-04-01T23:29:58.874453Z","end":"2026-04-01T23:29:59.469395Z","steps":["trace[1325283278] 'process raft request' (duration: 594.344199ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:59.469533Z","caller":"traceutil/trace.go:171","msg":"trace[776265303] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"594.750048ms","start":"2026-04-01T23:29:58.874773Z","end":"2026-04-01T23:29:59.469523Z","steps":["trace[776265303] 'process raft request' (duration: 594.134423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.469348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.874725Z","time spent":"594.561614ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:59.469572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.874768Z","time spent":"594.78715ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.469648Z","caller":"traceutil/trace.go:171","msg":"trace[503529390] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"588.736974ms","start":"2026-04-01T23:29:58.880903Z","end":"2026-04-01T23:29:59.46964Z","steps":["trace[503529390] 'process raft request' (duration: 588.057878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.469638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.874442Z","time spent":"595.109007ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.469675Z","caller":"traceutil/trace.go:171","msg":"trace[1402105813] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"582.508854ms","start":"2026-04-01T23:29:58.887158Z","end":"2026-04-01T23:29:59.469667Z","steps":["trace[1402105813] 'process raft request' (duration: 581.93059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.469682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.880875Z","time spent":"588.792035ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:59.469713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.887141Z","time spent":"582.553815ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.469965Z","caller":"traceutil/trace.go:171","msg":"trace[1605130761] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"534.958509ms","start":"2026-04-01T23:29:58.934991Z","end":"2026-04-01T23:29:59.46995Z","steps":["trace[1605130761] 'process raft request' (duration: 534.186171ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:59.469993Z","caller":"traceutil/trace.go:171","msg":"trace[1271842490] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"391.787332ms","start":"2026-04-01T23:29:59.078195Z","end":"2026-04-01T23:29:59.469982Z","steps":["trace[1271842490] 'process raft request' (duration: 391.166797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.470044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:59.078171Z","time spent":"391.848564ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:29:59.470031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.934965Z","time spent":"535.035561ms","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:29:59.470092Z","caller":"traceutil/trace.go:171","msg":"trace[1525866013] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:528; }","duration":"476.85436ms","start":"2026-04-01T23:29:58.993222Z","end":"2026-04-01T23:29:59.470076Z","steps":["trace[1525866013] 'read index received' (duration: 467.484895ms)","trace[1525866013] 'applied index is now lower than readState.Index' (duration: 9.367745ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:59.470324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.752478ms","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-04-01T23:29:59.470354Z","caller":"traceutil/trace.go:171","msg":"trace[1631495148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"476.817809ms","start":"2026-04-01T23:29:58.993528Z","end":"2026-04-01T23:29:59.470346Z","steps":["trace[1631495148] 'agreement among raft nodes before linearized reading' (duration: 476.693456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.47038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.993512Z","time spent":"476.859241ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:29:59.470385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.159537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:29:59.470424Z","caller":"traceutil/trace.go:171","msg":"trace[1928436287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"477.267281ms","start":"2026-04-01T23:29:58.993144Z","end":"2026-04-01T23:29:59.470412Z","steps":["trace[1928436287] 'agreement among raft nodes before linearized reading' (duration: 477.037455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.470468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:58.993112Z","time spent":"477.348702ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:29:59.470574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.916405ms","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-04-01T23:29:59.470603Z","caller":"traceutil/trace.go:171","msg":"trace[1870513045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"445.944676ms","start":"2026-04-01T23:29:59.024652Z","end":"2026-04-01T23:29:59.470596Z","steps":["trace[1870513045] 'agreement among raft nodes before linearized reading' (duration: 445.894415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:59.470623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:59.024557Z","time spent":"446.061229ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-01T23:30:03.024895Z","caller":"traceutil/trace.go:171","msg":"trace[1695744240] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"620.787815ms","start":"2026-04-01T23:30:02.404022Z","end":"2026-04-01T23:30:03.02481Z","steps":["trace[1695744240] 'process raft request' (duration: 620.624101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:03.025097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:02.404005Z","time spent":"620.98858ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:03.967225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754688213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:30:04.310997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.224878ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:04.311156Z","caller":"traceutil/trace.go:171","msg":"trace[794584390] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"548.393752ms","start":"2026-04-01T23:30:03.762737Z","end":"2026-04-01T23:30:04.31113Z","steps":["trace[794584390] 'range keys from in-memory index tree' (duration: 548.205888ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:04.422491Z","caller":"traceutil/trace.go:171","msg":"trace[22549183] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"956.128679ms","start":"2026-04-01T23:30:03.466331Z","end":"2026-04-01T23:30:04.42246Z","steps":["trace[22549183] 'read index received' (duration: 954.478059ms)","trace[22549183] 'applied index is now lower than readState.Index' (duration: 1.64913ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:04.422635Z","caller":"traceutil/trace.go:171","msg":"trace[290121434] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.855411508s","start":"2026-04-01T23:30:02.567213Z","end":"2026-04-01T23:30:04.422624Z","steps":["trace[290121434] 'process raft request' (duration: 1.854458525s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:04.42276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:02.567184Z","time spent":"1.85548228s","remote":"127.0.0.1:54938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:30:04.423017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.652932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:04.423136Z","caller":"traceutil/trace.go:171","msg":"trace[1718216907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"956.836486ms","start":"2026-04-01T23:30:03.46627Z","end":"2026-04-01T23:30:04.423107Z","steps":["trace[1718216907] 'agreement among raft nodes before linearized reading' (duration: 956.59439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:04.423179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:03.466248Z","time spent":"956.922628ms","remote":"127.0.0.1:54842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:04.423607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.643391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T23:30:04.423718Z","caller":"traceutil/trace.go:171","msg":"trace[585082604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"912.821816ms","start":"2026-04-01T23:30:03.51087Z","end":"2026-04-01T23:30:04.423692Z","steps":["trace[585082604] 'agreement among raft nodes before linearized reading' (duration: 912.520959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:04.423726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.179972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:30:04.423754Z","caller":"traceutil/trace.go:171","msg":"trace[35453107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"905.228534ms","start":"2026-04-01T23:30:03.518518Z","end":"2026-04-01T23:30:04.423746Z","steps":["trace[35453107] 'agreement among raft nodes before linearized reading' (duration: 905.09058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:04.423759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:03.510844Z","time spent":"912.907318ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:30:04.42378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:03.518507Z","time spent":"905.267524ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:04.423954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.030446ms","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-04-01T23:30:04.423982Z","caller":"traceutil/trace.go:171","msg":"trace[1768916430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"907.096568ms","start":"2026-04-01T23:30:03.516877Z","end":"2026-04-01T23:30:04.423973Z","steps":["trace[1768916430] 'agreement among raft nodes before linearized reading' (duration: 907.001266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:04.424003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:03.51683Z","time spent":"907.16704ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:09.221545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.471495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mv7tb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-01T23:30:09.221665Z","caller":"traceutil/trace.go:171","msg":"trace[896615424] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mv7tb; range_end:; response_count:1; response_revision:632; }","duration":"244.68368ms","start":"2026-04-01T23:30:08.976956Z","end":"2026-04-01T23:30:09.22164Z","steps":["trace[896615424] 'range keys from in-memory index tree' (duration: 244.352673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:09.221974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.69811ms","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-04-01T23:30:09.22209Z","caller":"traceutil/trace.go:171","msg":"trace[1395714100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"175.869164ms","start":"2026-04-01T23:30:09.046198Z","end":"2026-04-01T23:30:09.222067Z","steps":["trace[1395714100] 'range keys from in-memory index tree' (duration: 174.802768ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:09.401281Z","caller":"traceutil/trace.go:171","msg":"trace[1655925736] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"165.781681ms","start":"2026-04-01T23:30:09.235469Z","end":"2026-04-01T23:30:09.401251Z","steps":["trace[1655925736] 'process raft request' (duration: 162.81584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.661783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194205401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:11.661912Z","caller":"traceutil/trace.go:171","msg":"trace[2029258848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.194444687s","start":"2026-04-01T23:30:10.467442Z","end":"2026-04-01T23:30:11.661887Z","steps":["trace[2029258848] 'range keys from in-memory index tree' (duration: 1.194033527s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.66196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.466848Z","time spent":"1.195099552s","remote":"127.0.0.1:54842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:11.66195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098994609s","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-04-01T23:30:11.662048Z","caller":"traceutil/trace.go:171","msg":"trace[95973915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"1.099170823s","start":"2026-04-01T23:30:10.562854Z","end":"2026-04-01T23:30:11.662025Z","steps":["trace[95973915] 'range keys from in-memory index tree' (duration: 1.098782933s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.662101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.043697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-01T23:30:11.662045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118550979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T23:30:11.662159Z","caller":"traceutil/trace.go:171","msg":"trace[1980597779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"409.153521ms","start":"2026-04-01T23:30:11.25299Z","end":"2026-04-01T23:30:11.662143Z","steps":["trace[1980597779] 'range keys from in-memory index tree' (duration: 408.853083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.662191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:11.252962Z","time spent":"409.220582ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T23:30:11.66219Z","caller":"traceutil/trace.go:171","msg":"trace[994353642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.118753554s","start":"2026-04-01T23:30:10.543409Z","end":"2026-04-01T23:30:11.662163Z","steps":["trace[994353642] 'range keys from in-memory index tree' (duration: 1.118299733s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.662246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.543392Z","time spent":"1.118842357s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:30:11.663114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100649808s","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-04-01T23:30:11.662093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.562826Z","time spent":"1.099257955s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:30:11.663204Z","caller":"traceutil/trace.go:171","msg":"trace[162196824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"1.100804602s","start":"2026-04-01T23:30:10.562379Z","end":"2026-04-01T23:30:11.663184Z","steps":["trace[162196824] 'range keys from in-memory index tree' (duration: 1.09906251s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.663274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.562341Z","time spent":"1.100922965s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:30:11.731212Z","caller":"traceutil/trace.go:171","msg":"trace[1193466198] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"204.267508ms","start":"2026-04-01T23:30:11.526912Z","end":"2026-04-01T23:30:11.73118Z","steps":["trace[1193466198] 'process raft request' (duration: 204.060313ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:11.785648Z","caller":"traceutil/trace.go:171","msg":"trace[1725639742] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"119.030365ms","start":"2026-04-01T23:30:11.666588Z","end":"2026-04-01T23:30:11.785618Z","steps":["trace[1725639742] 'process raft request' (duration: 118.215465ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:11.785886Z","caller":"traceutil/trace.go:171","msg":"trace[2015029839] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"119.200129ms","start":"2026-04-01T23:30:11.666676Z","end":"2026-04-01T23:30:11.785876Z","steps":["trace[2015029839] 'process raft request' (duration: 118.343589ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:11.786013Z","caller":"traceutil/trace.go:171","msg":"trace[1969758005] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"118.210885ms","start":"2026-04-01T23:30:11.667794Z","end":"2026-04-01T23:30:11.786005Z","steps":["trace[1969758005] 'process raft request' (duration: 117.261653ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:11.786127Z","caller":"traceutil/trace.go:171","msg":"trace[326816877] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"117.772705ms","start":"2026-04-01T23:30:11.668347Z","end":"2026-04-01T23:30:11.786119Z","steps":["trace[326816877] 'process raft request' (duration: 116.74449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.643337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.120394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:13.643445Z","caller":"traceutil/trace.go:171","msg":"trace[1771418374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"177.272258ms","start":"2026-04-01T23:30:13.466148Z","end":"2026-04-01T23:30:13.64342Z","steps":["trace[1771418374] 'range keys from in-memory index tree' (duration: 177.060473ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:21.434948Z","caller":"traceutil/trace.go:171","msg":"trace[426798900] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"118.734538ms","start":"2026-04-01T23:30:21.316182Z","end":"2026-04-01T23:30:21.434916Z","steps":["trace[426798900] 'process raft request' (duration: 118.570984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.62156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.285555ms","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-04-01T23:30:28.62178Z","caller":"traceutil/trace.go:171","msg":"trace[116581944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"763.558261ms","start":"2026-04-01T23:30:27.858189Z","end":"2026-04-01T23:30:28.621747Z","steps":["trace[116581944] 'range keys from in-memory index tree' (duration: 763.000668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.621982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:27.858166Z","time spent":"763.797667ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:28.6223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.576826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:30:28.622374Z","caller":"traceutil/trace.go:171","msg":"trace[1870978685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"867.666488ms","start":"2026-04-01T23:30:27.754691Z","end":"2026-04-01T23:30:28.622358Z","steps":["trace[1870978685] 'range keys from in-memory index tree' (duration: 867.041212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.622408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:27.754612Z","time spent":"867.787131ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:28.622509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.68196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T23:30:28.622845Z","caller":"traceutil/trace.go:171","msg":"trace[1923691840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"758.124521ms","start":"2026-04-01T23:30:27.86469Z","end":"2026-04-01T23:30:28.622814Z","steps":["trace[1923691840] 'range keys from in-memory index tree' (duration: 756.948332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.623159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:27.86468Z","time spent":"758.462079ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:30:28.622567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.114434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:30:28.623653Z","caller":"traceutil/trace.go:171","msg":"trace[198512997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"765.218152ms","start":"2026-04-01T23:30:27.858417Z","end":"2026-04-01T23:30:28.623635Z","steps":["trace[198512997] 'range keys from in-memory index tree' (duration: 763.330106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.623781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:27.858411Z","time spent":"765.350015ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:28.622636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.340806ms","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-04-01T23:30:28.624032Z","caller":"traceutil/trace.go:171","msg":"trace[114129651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"764.737469ms","start":"2026-04-01T23:30:27.859262Z","end":"2026-04-01T23:30:28.623999Z","steps":["trace[114129651] 'range keys from in-memory index tree' (duration: 762.204519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.624099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:27.859251Z","time spent":"764.830262ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T23:30:28.627622Z","caller":"traceutil/trace.go:171","msg":"trace[1821099612] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"161.373975ms","start":"2026-04-01T23:30:28.466223Z","end":"2026-04-01T23:30:28.627597Z","steps":["trace[1821099612] 'read index received' (duration: 161.140629ms)","trace[1821099612] 'applied index is now lower than readState.Index' (duration: 232.186µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:28.628032Z","caller":"traceutil/trace.go:171","msg":"trace[1307714382] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"513.747567ms","start":"2026-04-01T23:30:28.114269Z","end":"2026-04-01T23:30:28.628016Z","steps":["trace[1307714382] 'process raft request' (duration: 513.171103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.628186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.114231Z","time spent":"513.843609ms","remote":"127.0.0.1:55092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:28.628634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.410559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:28.628686Z","caller":"traceutil/trace.go:171","msg":"trace[1118506047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"162.510622ms","start":"2026-04-01T23:30:28.46616Z","end":"2026-04-01T23:30:28.628671Z","steps":["trace[1118506047] 'agreement among raft nodes before linearized reading' (duration: 162.398669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.628811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.621547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:28.628895Z","caller":"traceutil/trace.go:171","msg":"trace[1411732667] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:792; }","duration":"101.776111ms","start":"2026-04-01T23:30:28.527098Z","end":"2026-04-01T23:30:28.628874Z","steps":["trace[1411732667] 'agreement among raft nodes before linearized reading' (duration: 101.607837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.628948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.73147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:30:28.628997Z","caller":"traceutil/trace.go:171","msg":"trace[1483394450] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:792; }","duration":"101.853012ms","start":"2026-04-01T23:30:28.527131Z","end":"2026-04-01T23:30:28.628984Z","steps":["trace[1483394450] 'agreement among raft nodes before linearized reading' (duration: 101.73867ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:28.944333Z","caller":"traceutil/trace.go:171","msg":"trace[1366660160] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"314.510651ms","start":"2026-04-01T23:30:28.629785Z","end":"2026-04-01T23:30:28.944295Z","steps":["trace[1366660160] 'process raft request' (duration: 310.026033ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:28.944345Z","caller":"traceutil/trace.go:171","msg":"trace[742120282] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"314.146893ms","start":"2026-04-01T23:30:28.630173Z","end":"2026-04-01T23:30:28.94432Z","steps":["trace[742120282] 'read index received' (duration: 309.685666ms)","trace[742120282] 'applied index is now lower than readState.Index' (duration: 4.459307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:28.944467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.629761Z","time spent":"314.633175ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:28.944485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.298187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:28.94455Z","caller":"traceutil/trace.go:171","msg":"trace[220348193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"314.394519ms","start":"2026-04-01T23:30:28.630137Z","end":"2026-04-01T23:30:28.944531Z","steps":["trace[220348193] 'agreement among raft nodes before linearized reading' (duration: 314.267346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.944671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.630121Z","time spent":"314.536922ms","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:30:28.945064Z","caller":"traceutil/trace.go:171","msg":"trace[591395892] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"314.81055ms","start":"2026-04-01T23:30:28.630233Z","end":"2026-04-01T23:30:28.945044Z","steps":["trace[591395892] 'process raft request' (duration: 313.970499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.945167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.630213Z","time spent":"314.899851ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:28.945804Z","caller":"traceutil/trace.go:171","msg":"trace[134402398] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"314.475761ms","start":"2026-04-01T23:30:28.631312Z","end":"2026-04-01T23:30:28.945788Z","steps":["trace[134402398] 'process raft request' (duration: 313.666022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.945903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.631303Z","time spent":"314.550583ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:28.946359Z","caller":"traceutil/trace.go:171","msg":"trace[1732222206] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"314.303997ms","start":"2026-04-01T23:30:28.632041Z","end":"2026-04-01T23:30:28.946345Z","steps":["trace[1732222206] 'process raft request' (duration: 314.249616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.946454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.632033Z","time spent":"314.374498ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:28.94687Z","caller":"traceutil/trace.go:171","msg":"trace[835564342] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"315.219319ms","start":"2026-04-01T23:30:28.631451Z","end":"2026-04-01T23:30:28.94667Z","steps":["trace[835564342] 'process raft request' (duration: 314.292346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:28.947195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:28.631443Z","time spent":"315.552187ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:32.000944Z","caller":"traceutil/trace.go:171","msg":"trace[2138805735] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"137.87312ms","start":"2026-04-01T23:30:31.863035Z","end":"2026-04-01T23:30:32.000908Z","steps":["trace[2138805735] 'process raft request' (duration: 51.629824ms)","trace[2138805735] 'compare' (duration: 86.085972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:04.097618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.879221ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:04.097745Z","caller":"traceutil/trace.go:171","msg":"trace[13748528] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1010; }","duration":"335.117747ms","start":"2026-04-01T23:31:03.762608Z","end":"2026-04-01T23:31:04.097725Z","steps":["trace[13748528] 'range keys from in-memory index tree' (duration: 334.83203ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:05.421339Z","caller":"traceutil/trace.go:171","msg":"trace[1301853936] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"272.623253ms","start":"2026-04-01T23:31:05.148685Z","end":"2026-04-01T23:31:05.421308Z","steps":["trace[1301853936] 'process raft request' (duration: 272.414928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:06.492891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.958543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:31:06.493017Z","caller":"traceutil/trace.go:171","msg":"trace[2525679] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"1.340292938s","start":"2026-04-01T23:31:05.152703Z","end":"2026-04-01T23:31:06.492996Z","steps":["trace[2525679] 'read index received' (duration: 268.370471ms)","trace[2525679] 'applied index is now lower than readState.Index' (duration: 1.071920737s)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:06.49332Z","caller":"traceutil/trace.go:171","msg":"trace[311075897] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.341388374s","start":"2026-04-01T23:31:05.151898Z","end":"2026-04-01T23:31:06.493286Z","steps":["trace[311075897] 'process raft request' (duration: 723.903218ms)","trace[311075897] 'compare' (duration: 616.839781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:06.49344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340685018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-01T23:31:06.493455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340733068s","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-04-01T23:31:06.493477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:05.151868Z","time spent":"1.341521718s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:06.493523Z","caller":"traceutil/trace.go:171","msg":"trace[511981176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"1.34082241s","start":"2026-04-01T23:31:05.152678Z","end":"2026-04-01T23:31:06.493501Z","steps":["trace[511981176] 'agreement among raft nodes before linearized reading' (duration: 1.340552134s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:06.49353Z","caller":"traceutil/trace.go:171","msg":"trace[811252515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"1.340845721s","start":"2026-04-01T23:31:05.152665Z","end":"2026-04-01T23:31:06.493511Z","steps":["trace[811252515] 'agreement among raft nodes before linearized reading' (duration: 1.340589044s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:06.493567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:05.152666Z","time spent":"1.340890942s","remote":"127.0.0.1:55118","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-04-01T23:31:06.49357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:05.152651Z","time spent":"1.340911923s","remote":"127.0.0.1:55118","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":"info","ts":"2026-04-01T23:31:06.612902Z","caller":"traceutil/trace.go:171","msg":"trace[1841881931] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"119.825745ms","start":"2026-04-01T23:31:06.493052Z","end":"2026-04-01T23:31:06.612878Z","steps":["trace[1841881931] 'read index received' (duration: 114.745173ms)","trace[1841881931] 'applied index is now lower than readState.Index' (duration: 5.079572ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:06.613004Z","caller":"traceutil/trace.go:171","msg":"trace[485280188] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"994.39327ms","start":"2026-04-01T23:31:05.618564Z","end":"2026-04-01T23:31:06.612958Z","steps":["trace[485280188] 'process raft request' (duration: 989.149214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:06.613158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:05.618546Z","time spent":"994.527774ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:06.613153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.631553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:31:06.613178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458358321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:31:06.613241Z","caller":"traceutil/trace.go:171","msg":"trace[1305819854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"149.791407ms","start":"2026-04-01T23:31:06.463427Z","end":"2026-04-01T23:31:06.613218Z","steps":["trace[1305819854] 'agreement among raft nodes before linearized reading' (duration: 149.610952ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:06.613268Z","caller":"traceutil/trace.go:171","msg":"trace[507188574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"1.458496283s","start":"2026-04-01T23:31:05.154753Z","end":"2026-04-01T23:31:06.613249Z","steps":["trace[507188574] 'agreement among raft nodes before linearized reading' (duration: 1.458225897s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:06.61332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:05.154739Z","time spent":"1.458567576s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-01T23:31:06.615844Z","caller":"traceutil/trace.go:171","msg":"trace[1215090608] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"118.500733ms","start":"2026-04-01T23:31:06.497324Z","end":"2026-04-01T23:31:06.615825Z","steps":["trace[1215090608] 'process raft request' (duration: 117.997211ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:06.616064Z","caller":"traceutil/trace.go:171","msg":"trace[273147201] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"118.492413ms","start":"2026-04-01T23:31:06.497557Z","end":"2026-04-01T23:31:06.616049Z","steps":["trace[273147201] 'process raft request' (duration: 117.858177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:28.272079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754689329,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:31:28.344197Z","caller":"traceutil/trace.go:171","msg":"trace[1970442279] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"572.687748ms","start":"2026-04-01T23:31:27.771484Z","end":"2026-04-01T23:31:28.344171Z","steps":["trace[1970442279] 'read index received' (duration: 572.319959ms)","trace[1970442279] 'applied index is now lower than readState.Index' (duration: 366.189µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:28.344335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.824041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:31:28.344369Z","caller":"traceutil/trace.go:171","msg":"trace[1516279231] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1084; }","duration":"572.881563ms","start":"2026-04-01T23:31:27.771478Z","end":"2026-04-01T23:31:28.344359Z","steps":["trace[1516279231] 'agreement among raft nodes before linearized reading' (duration: 572.78901ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:28.344334Z","caller":"traceutil/trace.go:171","msg":"trace[1084063426] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"790.732197ms","start":"2026-04-01T23:31:27.553569Z","end":"2026-04-01T23:31:28.344301Z","steps":["trace[1084063426] 'process raft request' (duration: 790.394769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:28.344408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:27.771419Z","time spent":"572.979415ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:31:28.344511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:27.553548Z","time spent":"790.88421ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:31.029469Z","caller":"traceutil/trace.go:171","msg":"trace[624593866] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"208.484829ms","start":"2026-04-01T23:31:30.820955Z","end":"2026-04-01T23:31:31.02944Z","steps":["trace[624593866] 'process raft request' (duration: 208.326975ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:31.067191Z","caller":"traceutil/trace.go:171","msg":"trace[1982150465] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"245.519471ms","start":"2026-04-01T23:31:30.821647Z","end":"2026-04-01T23:31:31.067166Z","steps":["trace[1982150465] 'process raft request' (duration: 215.441936ms)","trace[1982150465] 'compare' (duration: 29.953282ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:32.105968Z","caller":"traceutil/trace.go:171","msg":"trace[340302859] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"232.733103ms","start":"2026-04-01T23:31:31.873106Z","end":"2026-04-01T23:31:32.105839Z","steps":["trace[340302859] 'process raft request' (duration: 158.385933ms)","trace[340302859] 'compare' (duration: 74.150425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:32.671415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.991663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:32.671775Z","caller":"traceutil/trace.go:171","msg":"trace[792399170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"207.31024ms","start":"2026-04-01T23:31:32.464351Z","end":"2026-04-01T23:31:32.671661Z","steps":["trace[792399170] 'range keys from in-memory index tree' (duration: 206.91279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:32.671492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.706023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T23:31:32.673032Z","caller":"traceutil/trace.go:171","msg":"trace[1646079924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"254.554488ms","start":"2026-04-01T23:31:32.418456Z","end":"2026-04-01T23:31:32.673011Z","steps":["trace[1646079924] 'range keys from in-memory index tree' (duration: 252.509319ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:32.793901Z","caller":"traceutil/trace.go:171","msg":"trace[603381325] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"106.618657ms","start":"2026-04-01T23:31:32.687255Z","end":"2026-04-01T23:31:32.793873Z","steps":["trace[603381325] 'read index received' (duration: 106.423983ms)","trace[603381325] 'applied index is now lower than readState.Index' (duration: 193.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:32.794013Z","caller":"traceutil/trace.go:171","msg":"trace[158128551] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"116.336471ms","start":"2026-04-01T23:31:32.677634Z","end":"2026-04-01T23:31:32.79397Z","steps":["trace[158128551] 'process raft request' (duration: 116.049444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:32.794161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.888403ms","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-04-01T23:31:32.794325Z","caller":"traceutil/trace.go:171","msg":"trace[899908044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"107.073348ms","start":"2026-04-01T23:31:32.687193Z","end":"2026-04-01T23:31:32.794266Z","steps":["trace[899908044] 'agreement among raft nodes before linearized reading' (duration: 106.802121ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:33.005227Z","caller":"traceutil/trace.go:171","msg":"trace[1910971904] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"244.445745ms","start":"2026-04-01T23:31:32.760762Z","end":"2026-04-01T23:31:33.005207Z","steps":["trace[1910971904] 'process raft request' (duration: 145.056312ms)","trace[1910971904] 'compare' (duration: 98.251706ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:33.005321Z","caller":"traceutil/trace.go:171","msg":"trace[416937802] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"205.143829ms","start":"2026-04-01T23:31:32.800141Z","end":"2026-04-01T23:31:33.005284Z","steps":["trace[416937802] 'process raft request' (duration: 204.468263ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:33.00503Z","caller":"traceutil/trace.go:171","msg":"trace[384656680] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1142; }","duration":"191.279135ms","start":"2026-04-01T23:31:32.813718Z","end":"2026-04-01T23:31:33.004997Z","steps":["trace[384656680] 'read index received' (duration: 92.405015ms)","trace[384656680] 'applied index is now lower than readState.Index' (duration: 98.87256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:33.006077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.894109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:33.006131Z","caller":"traceutil/trace.go:171","msg":"trace[199569324] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1099; }","duration":"123.020992ms","start":"2026-04-01T23:31:32.883097Z","end":"2026-04-01T23:31:33.006118Z","steps":["trace[199569324] 'agreement among raft nodes before linearized reading' (duration: 122.827177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:33.006352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.420372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:31:33.006474Z","caller":"traceutil/trace.go:171","msg":"trace[1624352014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"192.766311ms","start":"2026-04-01T23:31:32.813677Z","end":"2026-04-01T23:31:33.006444Z","steps":["trace[1624352014] 'agreement among raft nodes before linearized reading' (duration: 192.076364ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:33.168722Z","caller":"traceutil/trace.go:171","msg":"trace[160940967] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"135.846551ms","start":"2026-04-01T23:31:33.032836Z","end":"2026-04-01T23:31:33.168682Z","steps":["trace[160940967] 'read index received' (duration: 73.11343ms)","trace[160940967] 'applied index is now lower than readState.Index' (duration: 62.731331ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:33.168918Z","caller":"traceutil/trace.go:171","msg":"trace[981536425] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"157.483381ms","start":"2026-04-01T23:31:33.011288Z","end":"2026-04-01T23:31:33.168772Z","steps":["trace[981536425] 'process raft request' (duration: 94.72316ms)","trace[981536425] 'compare' (duration: 62.457874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:33.169107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.23563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:31:33.169227Z","caller":"traceutil/trace.go:171","msg":"trace[1022208238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"136.426644ms","start":"2026-04-01T23:31:33.03277Z","end":"2026-04-01T23:31:33.169197Z","steps":["trace[1022208238] 'agreement among raft nodes before linearized reading' (duration: 136.074156ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:47.462224Z","caller":"traceutil/trace.go:171","msg":"trace[1216570530] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"222.125589ms","start":"2026-04-01T23:31:47.240077Z","end":"2026-04-01T23:31:47.462202Z","steps":["trace[1216570530] 'process raft request' (duration: 221.997316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:47.462812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.05545ms","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-04-01T23:31:47.462874Z","caller":"traceutil/trace.go:171","msg":"trace[102743774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"198.200754ms","start":"2026-04-01T23:31:47.264663Z","end":"2026-04-01T23:31:47.462864Z","steps":["trace[102743774] 'agreement among raft nodes before linearized reading' (duration: 198.00028ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:47.462626Z","caller":"traceutil/trace.go:171","msg":"trace[80982980] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1255; }","duration":"197.828225ms","start":"2026-04-01T23:31:47.264739Z","end":"2026-04-01T23:31:47.462567Z","steps":["trace[80982980] 'read index received' (duration: 197.815075ms)","trace[80982980] 'applied index is now lower than readState.Index' (duration: 9.43µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:47.561909Z","caller":"traceutil/trace.go:171","msg":"trace[765784878] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"227.61203ms","start":"2026-04-01T23:31:47.334235Z","end":"2026-04-01T23:31:47.561847Z","steps":["trace[765784878] 'process raft request' (duration: 227.423696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:47.562257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.221594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:47.562365Z","caller":"traceutil/trace.go:171","msg":"trace[2061975111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1208; }","duration":"245.426789ms","start":"2026-04-01T23:31:47.316913Z","end":"2026-04-01T23:31:47.56234Z","steps":["trace[2061975111] 'agreement among raft nodes before linearized reading' (duration: 245.012839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:47.562362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.651037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:31:47.562465Z","caller":"traceutil/trace.go:171","msg":"trace[483267380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"273.794701ms","start":"2026-04-01T23:31:47.288644Z","end":"2026-04-01T23:31:47.562439Z","steps":["trace[483267380] 'agreement among raft nodes before linearized reading' (duration: 273.297039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:49.58837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113399754689603,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:31:49.647001Z","caller":"traceutil/trace.go:171","msg":"trace[1388158501] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"737.924287ms","start":"2026-04-01T23:31:48.909039Z","end":"2026-04-01T23:31:49.646963Z","steps":["trace[1388158501] 'process raft request' (duration: 737.729232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:49.647193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:48.909016Z","time spent":"738.079311ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:31:50.354517Z","caller":"traceutil/trace.go:171","msg":"trace[1927176442] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1259; }","duration":"1.266582953s","start":"2026-04-01T23:31:49.087896Z","end":"2026-04-01T23:31:50.354479Z","steps":["trace[1927176442] 'read index received' (duration: 558.832412ms)","trace[1927176442] 'applied index is now lower than readState.Index' (duration: 707.749261ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:50.35474Z","caller":"traceutil/trace.go:171","msg":"trace[2070865340] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"1.281872041s","start":"2026-04-01T23:31:49.07285Z","end":"2026-04-01T23:31:50.354722Z","steps":["trace[2070865340] 'process raft request' (duration: 1.197977085s)","trace[2070865340] 'compare' (duration: 83.494047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:31:50.354896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:49.072829Z","time spent":"1.281961474s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:50.354998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.546177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:31:50.355145Z","caller":"traceutil/trace.go:171","msg":"trace[2063425864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"888.716291ms","start":"2026-04-01T23:31:49.466375Z","end":"2026-04-01T23:31:50.355091Z","steps":["trace[2063425864] 'agreement among raft nodes before linearized reading' (duration: 888.412474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.355187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:49.466358Z","time spent":"888.821923ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:31:50.355367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.620086ms","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-04-01T23:31:50.355386Z","caller":"traceutil/trace.go:171","msg":"trace[109364910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"788.636516ms","start":"2026-04-01T23:31:49.566742Z","end":"2026-04-01T23:31:50.355379Z","steps":["trace[109364910] 'agreement among raft nodes before linearized reading' (duration: 788.567804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.355404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:49.566705Z","time spent":"788.693608ms","remote":"127.0.0.1:55118","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-04-01T23:31:50.355493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267599318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-01T23:31:50.35549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.087877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:31:50.355536Z","caller":"traceutil/trace.go:171","msg":"trace[1224195659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"1.26765992s","start":"2026-04-01T23:31:49.087865Z","end":"2026-04-01T23:31:50.355525Z","steps":["trace[1224195659] 'agreement among raft nodes before linearized reading' (duration: 1.267493466s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.35553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.112653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:31:50.355591Z","caller":"traceutil/trace.go:171","msg":"trace[463531735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"784.257411ms","start":"2026-04-01T23:31:49.571308Z","end":"2026-04-01T23:31:50.355566Z","steps":["trace[463531735] 'agreement among raft nodes before linearized reading' (duration: 783.942023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.355655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:49.571279Z","time spent":"784.366582ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:31:50.355652Z","caller":"traceutil/trace.go:171","msg":"trace[154240426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"887.291637ms","start":"2026-04-01T23:31:49.468331Z","end":"2026-04-01T23:31:50.355623Z","steps":["trace[154240426] 'agreement among raft nodes before linearized reading' (duration: 887.02483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:50.355719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:49.468325Z","time spent":"887.385019ms","remote":"127.0.0.1:54842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:31:50.355582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:49.087858Z","time spent":"1.267717311s","remote":"127.0.0.1:55118","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-04-01T23:32:07.601668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.432471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:32:07.602178Z","caller":"traceutil/trace.go:171","msg":"trace[51424034] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.095912201s","start":"2026-04-01T23:32:06.506222Z","end":"2026-04-01T23:32:07.602134Z","steps":["trace[51424034] 'process raft request' (duration: 338.895045ms)","trace[51424034] 'compare' (duration: 756.166835ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:07.602357Z","caller":"traceutil/trace.go:171","msg":"trace[713072185] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"1.059798863s","start":"2026-04-01T23:32:06.542534Z","end":"2026-04-01T23:32:07.602333Z","steps":["trace[713072185] 'read index received' (duration: 302.602414ms)","trace[713072185] 'applied index is now lower than readState.Index' (duration: 757.193949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:07.602377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:06.506203Z","time spent":"1.096064635s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:32:07.602189Z","caller":"traceutil/trace.go:171","msg":"trace[1331495391] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.093424101s","start":"2026-04-01T23:32:06.508723Z","end":"2026-04-01T23:32:07.602148Z","steps":["trace[1331495391] 'process raft request' (duration: 1.093094563s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:07.602578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:06.50871Z","time spent":"1.09382576s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:32:07.673269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130189425s","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-04-01T23:32:07.673343Z","caller":"traceutil/trace.go:171","msg":"trace[840509445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.130299948s","start":"2026-04-01T23:32:06.543026Z","end":"2026-04-01T23:32:07.673326Z","steps":["trace[840509445] 'agreement among raft nodes before linearized reading' (duration: 1.130126554s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:07.673345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.148439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:32:07.673399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:06.542974Z","time spent":"1.13041773s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:32:07.673442Z","caller":"traceutil/trace.go:171","msg":"trace[996918979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"207.302113ms","start":"2026-04-01T23:32:07.466105Z","end":"2026-04-01T23:32:07.673408Z","steps":["trace[996918979] 'agreement among raft nodes before linearized reading' (duration: 207.137739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:07.673473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130894042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:32:07.673582Z","caller":"traceutil/trace.go:171","msg":"trace[1335991311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.131065906s","start":"2026-04-01T23:32:06.542486Z","end":"2026-04-01T23:32:07.673552Z","steps":["trace[1335991311] 'agreement among raft nodes before linearized reading' (duration: 1.059977278s)","trace[1335991311] 'range keys from in-memory index tree' (duration: 70.769431ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:07.673637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:06.542469Z","time spent":"1.131157748s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:32:07.935395Z","caller":"traceutil/trace.go:171","msg":"trace[523207208] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"136.05151ms","start":"2026-04-01T23:32:07.799298Z","end":"2026-04-01T23:32:07.93535Z","steps":["trace[523207208] 'process raft request' (duration: 37.02984ms)","trace[523207208] 'compare' (duration: 98.812005ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:08.017182Z","caller":"traceutil/trace.go:171","msg":"trace[199889123] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"212.698023ms","start":"2026-04-01T23:32:07.804447Z","end":"2026-04-01T23:32:08.017145Z","steps":["trace[199889123] 'process raft request' (duration: 212.491978ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:08.20466Z","caller":"traceutil/trace.go:171","msg":"trace[1290137699] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"123.361625ms","start":"2026-04-01T23:32:08.081275Z","end":"2026-04-01T23:32:08.204637Z","steps":["trace[1290137699] 'process raft request' (duration: 51.285233ms)","trace[1290137699] 'compare' (duration: 71.946639ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:08.762707Z","caller":"traceutil/trace.go:171","msg":"trace[1598812076] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"104.266186ms","start":"2026-04-01T23:32:08.658415Z","end":"2026-04-01T23:32:08.762682Z","steps":["trace[1598812076] 'read index received' (duration: 79.312646ms)","trace[1598812076] 'applied index is now lower than readState.Index' (duration: 24.95233ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:08.76277Z","caller":"traceutil/trace.go:171","msg":"trace[879140559] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"255.404009ms","start":"2026-04-01T23:32:08.507328Z","end":"2026-04-01T23:32:08.762732Z","steps":["trace[879140559] 'process raft request' (duration: 230.450079ms)","trace[879140559] 'compare' (duration: 24.502329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:08.763146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.463071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:32:08.763206Z","caller":"traceutil/trace.go:171","msg":"trace[1865386271] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1292; }","duration":"104.81972ms","start":"2026-04-01T23:32:08.658369Z","end":"2026-04-01T23:32:08.763189Z","steps":["trace[1865386271] 'agreement among raft nodes before linearized reading' (duration: 104.474902ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:09.519074Z","caller":"traceutil/trace.go:171","msg":"trace[1065197081] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"357.862201ms","start":"2026-04-01T23:32:09.161173Z","end":"2026-04-01T23:32:09.519035Z","steps":["trace[1065197081] 'process raft request' (duration: 357.707128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:09.519238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:09.160963Z","time spent":"358.2027ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:32:09.742685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.914838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:32:09.74277Z","caller":"traceutil/trace.go:171","msg":"trace[845186899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"136.04484ms","start":"2026-04-01T23:32:09.606702Z","end":"2026-04-01T23:32:09.742747Z","steps":["trace[845186899] 'range keys from in-memory index tree' (duration: 135.760314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:09.742906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.857345ms","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-04-01T23:32:09.742931Z","caller":"traceutil/trace.go:171","msg":"trace[49402479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"135.901857ms","start":"2026-04-01T23:32:09.607023Z","end":"2026-04-01T23:32:09.742925Z","steps":["trace[49402479] 'range keys from in-memory index tree' (duration: 135.803124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:09.742978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.90542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:32:09.743054Z","caller":"traceutil/trace.go:171","msg":"trace[1325295118] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1293; }","duration":"149.027982ms","start":"2026-04-01T23:32:09.594011Z","end":"2026-04-01T23:32:09.743039Z","steps":["trace[1325295118] 'count revisions from in-memory index tree' (duration: 148.779407ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:10.106332Z","caller":"traceutil/trace.go:171","msg":"trace[724828691] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1347; }","duration":"357.563994ms","start":"2026-04-01T23:32:09.748742Z","end":"2026-04-01T23:32:10.106306Z","steps":["trace[724828691] 'read index received' (duration: 93.977449ms)","trace[724828691] 'applied index is now lower than readState.Index' (duration: 263.585075ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:10.106393Z","caller":"traceutil/trace.go:171","msg":"trace[1643376410] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"359.238305ms","start":"2026-04-01T23:32:09.747123Z","end":"2026-04-01T23:32:10.106362Z","steps":["trace[1643376410] 'process raft request' (duration: 359.115462ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:10.106387Z","caller":"traceutil/trace.go:171","msg":"trace[639435890] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"359.287376ms","start":"2026-04-01T23:32:09.74706Z","end":"2026-04-01T23:32:10.106347Z","steps":["trace[639435890] 'process raft request' (duration: 344.584203ms)","trace[639435890] 'compare' (duration: 14.458847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:10.106547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:09.747117Z","time spent":"359.347057ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:32:10.106579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:09.747049Z","time spent":"359.43553ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:32:10.106601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.846482ms","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-04-01T23:32:10.106671Z","caller":"traceutil/trace.go:171","msg":"trace[1388002118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"357.922064ms","start":"2026-04-01T23:32:09.74873Z","end":"2026-04-01T23:32:10.106652Z","steps":["trace[1388002118] 'agreement among raft nodes before linearized reading' (duration: 357.736359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:10.106719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:09.748675Z","time spent":"358.033076ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:32:10.106613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.846256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T23:32:10.106774Z","caller":"traceutil/trace.go:171","msg":"trace[983030094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"166.056901ms","start":"2026-04-01T23:32:09.940702Z","end":"2026-04-01T23:32:10.106758Z","steps":["trace[983030094] 'agreement among raft nodes before linearized reading' (duration: 165.769874ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:10.401657Z","caller":"traceutil/trace.go:171","msg":"trace[301623391] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"290.097393ms","start":"2026-04-01T23:32:10.111529Z","end":"2026-04-01T23:32:10.401626Z","steps":["trace[301623391] 'process raft request' (duration: 273.938384ms)","trace[301623391] 'compare' (duration: 16.043736ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:11.092226Z","caller":"traceutil/trace.go:171","msg":"trace[517186081] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"324.50946ms","start":"2026-04-01T23:32:10.767694Z","end":"2026-04-01T23:32:11.092204Z","steps":["trace[517186081] 'process raft request' (duration: 324.352016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:11.092371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:10.767672Z","time spent":"324.621972ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:32:11.141625Z","caller":"traceutil/trace.go:171","msg":"trace[1116579774] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1352; }","duration":"220.607413ms","start":"2026-04-01T23:32:10.920994Z","end":"2026-04-01T23:32:11.141601Z","steps":["trace[1116579774] 'read index received' (duration: 171.073062ms)","trace[1116579774] 'applied index is now lower than readState.Index' (duration: 49.533441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:11.141831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.810727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:32:11.141898Z","caller":"traceutil/trace.go:171","msg":"trace[1151784967] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1300; }","duration":"220.92952ms","start":"2026-04-01T23:32:10.920949Z","end":"2026-04-01T23:32:11.141879Z","steps":["trace[1151784967] 'agreement among raft nodes before linearized reading' (duration: 220.769796ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:11.142137Z","caller":"traceutil/trace.go:171","msg":"trace[875723540] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"365.27475ms","start":"2026-04-01T23:32:10.77685Z","end":"2026-04-01T23:32:11.142125Z","steps":["trace[875723540] 'process raft request' (duration: 364.646555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:11.14224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:32:10.776836Z","time spent":"365.345862ms","remote":"127.0.0.1:54924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":785,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:32:19.3128Z","caller":"traceutil/trace.go:171","msg":"trace[1408061124] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"198.403969ms","start":"2026-04-01T23:32:19.11437Z","end":"2026-04-01T23:32:19.312774Z","steps":["trace[1408061124] 'process raft request' (duration: 198.259466ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:20.031993Z","caller":"traceutil/trace.go:171","msg":"trace[1152761116] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"125.910067ms","start":"2026-04-01T23:32:19.906055Z","end":"2026-04-01T23:32:20.031965Z","steps":["trace[1152761116] 'read index received' (duration: 125.751083ms)","trace[1152761116] 'applied index is now lower than readState.Index' (duration: 157.994µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:20.032106Z","caller":"traceutil/trace.go:171","msg":"trace[1125130471] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"147.187627ms","start":"2026-04-01T23:32:19.884881Z","end":"2026-04-01T23:32:20.032069Z","steps":["trace[1125130471] 'process raft request' (duration: 146.917271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:20.032219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.140292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-01T23:32:20.03228Z","caller":"traceutil/trace.go:171","msg":"trace[526217721] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1370; }","duration":"126.260395ms","start":"2026-04-01T23:32:19.906001Z","end":"2026-04-01T23:32:20.032262Z","steps":["trace[526217721] 'agreement among raft nodes before linearized reading' (duration: 126.06328ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:20.255855Z","caller":"traceutil/trace.go:171","msg":"trace[1295624680] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"104.284367ms","start":"2026-04-01T23:32:20.151545Z","end":"2026-04-01T23:32:20.25583Z","steps":["trace[1295624680] 'read index received' (duration: 11.196769ms)","trace[1295624680] 'applied index is now lower than readState.Index' (duration: 93.086648ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:20.256037Z","caller":"traceutil/trace.go:171","msg":"trace[1850210574] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"215.92898ms","start":"2026-04-01T23:32:20.040074Z","end":"2026-04-01T23:32:20.256003Z","steps":["trace[1850210574] 'process raft request' (duration: 122.74913ms)","trace[1850210574] 'compare' (duration: 92.904583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:20.256274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.916518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-01T23:32:20.256442Z","caller":"traceutil/trace.go:171","msg":"trace[1437177938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"104.105332ms","start":"2026-04-01T23:32:20.15231Z","end":"2026-04-01T23:32:20.256415Z","steps":["trace[1437177938] 'agreement among raft nodes before linearized reading' (duration: 103.828555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:20.256509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.85403ms","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-04-01T23:32:20.256683Z","caller":"traceutil/trace.go:171","msg":"trace[1703427430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"105.168118ms","start":"2026-04-01T23:32:20.151499Z","end":"2026-04-01T23:32:20.256667Z","steps":["trace[1703427430] 'agreement among raft nodes before linearized reading' (duration: 104.578174ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:38.511588Z","caller":"traceutil/trace.go:171","msg":"trace[1982412350] linearizableReadLoop","detail":"{readStateIndex:1524; appliedIndex:1523; }","duration":"114.671157ms","start":"2026-04-01T23:32:38.39689Z","end":"2026-04-01T23:32:38.511561Z","steps":["trace[1982412350] 'read index received' (duration: 114.513403ms)","trace[1982412350] 'applied index is now lower than readState.Index' (duration: 156.824µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:38.511656Z","caller":"traceutil/trace.go:171","msg":"trace[26393473] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"115.742642ms","start":"2026-04-01T23:32:38.395861Z","end":"2026-04-01T23:32:38.511603Z","steps":["trace[26393473] 'process raft request' (duration: 115.544977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:38.511833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.916153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:32:38.51193Z","caller":"traceutil/trace.go:171","msg":"trace[1608974289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1464; }","duration":"115.054245ms","start":"2026-04-01T23:32:38.396861Z","end":"2026-04-01T23:32:38.511915Z","steps":["trace[1608974289] 'agreement among raft nodes before linearized reading' (duration: 114.82896ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:38.565822Z","caller":"traceutil/trace.go:171","msg":"trace[977468943] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"166.83239ms","start":"2026-04-01T23:32:38.398942Z","end":"2026-04-01T23:32:38.565774Z","steps":["trace[977468943] 'process raft request' (duration: 143.418067ms)","trace[977468943] 'compare' (duration: 23.206148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:38.566088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.08931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-01T23:32:38.566171Z","caller":"traceutil/trace.go:171","msg":"trace[414563450] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1466; }","duration":"101.223503ms","start":"2026-04-01T23:32:38.464928Z","end":"2026-04-01T23:32:38.566151Z","steps":["trace[414563450] 'agreement among raft nodes before linearized reading' (duration: 101.010488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:38.566465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.384167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:32:38.566531Z","caller":"traceutil/trace.go:171","msg":"trace[1789328976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1466; }","duration":"101.50008ms","start":"2026-04-01T23:32:38.465017Z","end":"2026-04-01T23:32:38.566517Z","steps":["trace[1789328976] 'agreement among raft nodes before linearized reading' (duration: 100.804283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:38.877311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.127399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:32:38.877464Z","caller":"traceutil/trace.go:171","msg":"trace[747869258] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"270.83294ms","start":"2026-04-01T23:32:38.606605Z","end":"2026-04-01T23:32:38.877438Z","steps":["trace[747869258] 'process raft request' (duration: 100.482145ms)","trace[747869258] 'compare' (duration: 170.017537ms)"],"step_count":2}