{"level":"warn","ts":"2026-04-20T20:57:58.32765Z","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-20T20:57:58.327745Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.142: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.19.213.142:2380","--initial-cluster=instance=https://199.19.213.142:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.142:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.142: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-20T20:57:58.327816Z","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-20T20:57:58.327832Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-04-20T20:57:58.327855Z","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-20T20:57:58.328556Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"]} {"level":"info","ts":"2026-04-20T20:57:58.328634Z","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.19.213.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.142: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-20T20:57:58.332864Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.982555ms"} {"level":"info","ts":"2026-04-20T20:57:58.339933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54fca804e66e40f4","cluster-id":"374317481b3c77b9"} {"level":"info","ts":"2026-04-20T20:57:58.340011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T20:57:58.340034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 0"} {"level":"info","ts":"2026-04-20T20:57:58.340051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54fca804e66e40f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T20:57:58.34006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 1"} {"level":"info","ts":"2026-04-20T20:57:58.340107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"warn","ts":"2026-04-20T20:57:58.345897Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T20:57:58.347125Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T20:57:58.348286Z","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-20T20:57:58.350725Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54fca804e66e40f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T20:57:58.351889Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54fca804e66e40f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T20:57:58.35211Z","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-20T20:57:58.352194Z","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-20T20:57:58.352226Z","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-20T20:57:58.357621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"info","ts":"2026-04-20T20:57:58.357862Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","added-peer-id":"54fca804e66e40f4","added-peer-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-04-20T20:57:58.36093Z","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-20T20:57:58.361115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-04-20T20:57:58.361144Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-04-20T20:57:58.36135Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54fca804e66e40f4","initial-advertise-peer-urls":["https://199.19.213.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T20:57:58.361388Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T20:57:59.341299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T20:57:59.34136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T20:57:59.341384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgPreVoteResp from 54fca804e66e40f4 at term 1"} {"level":"info","ts":"2026-04-20T20:57:59.341404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became candidate at term 2"} {"level":"info","ts":"2026-04-20T20:57:59.341416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgVoteResp from 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-04-20T20:57:59.341432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became leader at term 2"} {"level":"info","ts":"2026-04-20T20:57:59.341446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54fca804e66e40f4 elected leader 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-04-20T20:57:59.342392Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:57:59.343489Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:57:59.34349Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54fca804e66e40f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.142:2379]}","request-path":"/0/members/54fca804e66e40f4/attributes","cluster-id":"374317481b3c77b9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T20:57:59.343634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:57:59.344192Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T20:57:59.344234Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T20:57:59.344394Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:57:59.344476Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:57:59.344499Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:57:59.347052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.142:2379"} {"level":"info","ts":"2026-04-20T20:57:59.347676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T20:58:26.877727Z","caller":"traceutil/trace.go:171","msg":"trace[1959242417] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"368.468956ms","start":"2026-04-20T20:58:26.509222Z","end":"2026-04-20T20:58:26.877691Z","steps":["trace[1959242417] 'process raft request' (duration: 368.363723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.878251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.509206Z","time spent":"368.605209ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.885641Z","caller":"traceutil/trace.go:171","msg":"trace[1885458806] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"354.055969ms","start":"2026-04-20T20:58:26.531445Z","end":"2026-04-20T20:58:26.885501Z","steps":["trace[1885458806] 'process raft request' (duration: 353.473036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.88577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.531439Z","time spent":"354.273194ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.885886Z","caller":"traceutil/trace.go:171","msg":"trace[1150155194] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"375.586187ms","start":"2026-04-20T20:58:26.510288Z","end":"2026-04-20T20:58:26.885874Z","steps":["trace[1150155194] 'process raft request' (duration: 374.356977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.885939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.510282Z","time spent":"375.631918ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.886038Z","caller":"traceutil/trace.go:171","msg":"trace[776006810] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"367.528764ms","start":"2026-04-20T20:58:26.518501Z","end":"2026-04-20T20:58:26.886029Z","steps":["trace[776006810] 'process raft request' (duration: 366.224022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.886603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.518492Z","time spent":"368.081577ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.886689Z","caller":"traceutil/trace.go:171","msg":"trace[8292149] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"358.094107ms","start":"2026-04-20T20:58:26.528585Z","end":"2026-04-20T20:58:26.886679Z","steps":["trace[8292149] 'process raft request' (duration: 356.209202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.886735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.528571Z","time spent":"358.142568ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.887091Z","caller":"traceutil/trace.go:171","msg":"trace[708554624] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"358.433974ms","start":"2026-04-20T20:58:26.528636Z","end":"2026-04-20T20:58:26.88707Z","steps":["trace[708554624] 'process raft request' (duration: 356.231821ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:26.887123Z","caller":"traceutil/trace.go:171","msg":"trace[679623800] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"345.520634ms","start":"2026-04-20T20:58:26.541585Z","end":"2026-04-20T20:58:26.887106Z","steps":["trace[679623800] 'process raft request' (duration: 343.362602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.887215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.541577Z","time spent":"345.608466ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.88743Z","caller":"traceutil/trace.go:171","msg":"trace[1091138759] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"341.479048ms","start":"2026-04-20T20:58:26.545938Z","end":"2026-04-20T20:58:26.887417Z","steps":["trace[1091138759] 'process raft request' (duration: 339.035369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.887503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.54593Z","time spent":"341.527969ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.887659Z","caller":"traceutil/trace.go:171","msg":"trace[521227243] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"326.131209ms","start":"2026-04-20T20:58:26.561495Z","end":"2026-04-20T20:58:26.887626Z","steps":["trace[521227243] 'process raft request' (duration: 323.507436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.88771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.561479Z","time spent":"326.207771ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.887913Z","caller":"traceutil/trace.go:171","msg":"trace[1873342321] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"299.689694ms","start":"2026-04-20T20:58:26.588184Z","end":"2026-04-20T20:58:26.887874Z","steps":["trace[1873342321] 'process raft request' (duration: 296.903928ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:26.888139Z","caller":"traceutil/trace.go:171","msg":"trace[123048103] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"359.400297ms","start":"2026-04-20T20:58:26.528685Z","end":"2026-04-20T20:58:26.888085Z","steps":["trace[123048103] 'process raft request' (duration: 356.207721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.888252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.528681Z","time spent":"359.538311ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:26.888492Z","caller":"traceutil/trace.go:171","msg":"trace[764164423] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"211.053016ms","start":"2026-04-20T20:58:26.677426Z","end":"2026-04-20T20:58:26.888479Z","steps":["trace[764164423] 'process raft request' (duration: 207.753917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:26.887207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:26.528633Z","time spent":"358.517926ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:58:27.317482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.143657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:58:27.317746Z","caller":"traceutil/trace.go:171","msg":"trace[1843672087] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"226.163619ms","start":"2026-04-20T20:58:27.091566Z","end":"2026-04-20T20:58:27.317729Z","steps":["trace[1843672087] 'process raft request' (duration: 226.083577ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:27.317793Z","caller":"traceutil/trace.go:171","msg":"trace[2102224831] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"227.352058ms","start":"2026-04-20T20:58:27.090413Z","end":"2026-04-20T20:58:27.317765Z","steps":["trace[2102224831] 'compare' (duration: 223.009523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:27.318387Z","caller":"traceutil/trace.go:171","msg":"trace[1475453274] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"215.444712ms","start":"2026-04-20T20:58:27.102914Z","end":"2026-04-20T20:58:27.318359Z","steps":["trace[1475453274] 'process raft request' (duration: 215.169415ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:29.762325Z","caller":"traceutil/trace.go:171","msg":"trace[786956449] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"285.331649ms","start":"2026-04-20T20:58:29.476935Z","end":"2026-04-20T20:58:29.762266Z","steps":["trace[786956449] 'process raft request' (duration: 285.206076ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:32.437363Z","caller":"traceutil/trace.go:171","msg":"trace[1939448771] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"199.667083ms","start":"2026-04-20T20:58:32.237667Z","end":"2026-04-20T20:58:32.437334Z","steps":["trace[1939448771] 'process raft request' (duration: 199.255823ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:32.450215Z","caller":"traceutil/trace.go:171","msg":"trace[722479673] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"196.659391ms","start":"2026-04-20T20:58:32.253523Z","end":"2026-04-20T20:58:32.450182Z","steps":["trace[722479673] 'process raft request' (duration: 195.620335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:33.615431Z","caller":"traceutil/trace.go:171","msg":"trace[1880298284] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"183.515336ms","start":"2026-04-20T20:58:33.431876Z","end":"2026-04-20T20:58:33.615391Z","steps":["trace[1880298284] 'process raft request' (duration: 182.657244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.266748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.907976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:58:34.266878Z","caller":"traceutil/trace.go:171","msg":"trace[1601820278] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"487.942193ms","start":"2026-04-20T20:58:33.778919Z","end":"2026-04-20T20:58:34.266861Z","steps":["trace[1601820278] 'read index received' (duration: 95.822µs)","trace[1601820278] 'applied index is now lower than readState.Index' (duration: 487.845091ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:34.266956Z","caller":"traceutil/trace.go:171","msg":"trace[1567171186] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"589.933181ms","start":"2026-04-20T20:58:33.676952Z","end":"2026-04-20T20:58:34.266885Z","steps":["trace[1567171186] 'process raft request' (duration: 75.782059ms)","trace[1567171186] 'compare' (duration: 513.713471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:34.26712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.311519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfqgh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T20:58:34.267167Z","caller":"traceutil/trace.go:171","msg":"trace[2087708828] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfqgh; range_end:; response_count:1; response_revision:609; }","duration":"335.421891ms","start":"2026-04-20T20:58:33.931733Z","end":"2026-04-20T20:58:34.267155Z","steps":["trace[2087708828] 'agreement among raft nodes before linearized reading' (duration: 335.236737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.267157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.22948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T20:58:34.267171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:33.676919Z","time spent":"590.156816ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:58:34.267212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:33.931706Z","time spent":"335.496473ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-dfqgh\" "} {"level":"info","ts":"2026-04-20T20:58:34.267238Z","caller":"traceutil/trace.go:171","msg":"trace[1914383503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"488.366004ms","start":"2026-04-20T20:58:33.778851Z","end":"2026-04-20T20:58:34.267217Z","steps":["trace[1914383503] 'agreement among raft nodes before linearized reading' (duration: 488.092447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.26729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:33.778823Z","time spent":"488.458895ms","remote":"127.0.0.1:40924","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-20T20:58:35.448313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539277749733616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:58:35.527418Z","caller":"traceutil/trace.go:171","msg":"trace[616778293] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"904.718758ms","start":"2026-04-20T20:58:34.622669Z","end":"2026-04-20T20:58:35.527388Z","steps":["trace[616778293] 'process raft request' (duration: 898.438607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:35.527591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:34.622646Z","time spent":"904.843551ms","remote":"127.0.0.1:40820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:58:35.538606Z","caller":"traceutil/trace.go:171","msg":"trace[6006700] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:633; }","duration":"590.946516ms","start":"2026-04-20T20:58:34.947638Z","end":"2026-04-20T20:58:35.538584Z","steps":["trace[6006700] 'read index received' (duration: 573.488777ms)","trace[6006700] 'applied index is now lower than readState.Index' (duration: 17.457089ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:35.538706Z","caller":"traceutil/trace.go:171","msg":"trace[1928045579] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"847.696249ms","start":"2026-04-20T20:58:34.691003Z","end":"2026-04-20T20:58:35.538699Z","steps":["trace[1928045579] 'process raft request' (duration: 847.089095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:35.53882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:34.690981Z","time spent":"847.765871ms","remote":"127.0.0.1:40738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:58:35.538904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.243403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:58:35.538965Z","caller":"traceutil/trace.go:171","msg":"trace[2116380782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"591.360946ms","start":"2026-04-20T20:58:34.947587Z","end":"2026-04-20T20:58:35.538948Z","steps":["trace[2116380782] 'agreement among raft nodes before linearized reading' (duration: 591.125651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:35.538992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:34.947567Z","time spent":"591.420567ms","remote":"127.0.0.1:40924","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-20T20:58:35.539078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.098177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:35.539105Z","caller":"traceutil/trace.go:171","msg":"trace[1945461783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"448.169329ms","start":"2026-04-20T20:58:35.090928Z","end":"2026-04-20T20:58:35.539098Z","steps":["trace[1945461783] 'agreement among raft nodes before linearized reading' (duration: 448.124008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:35.539048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.467779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T20:58:35.539124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:35.090904Z","time spent":"448.216049ms","remote":"127.0.0.1:40642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:58:35.539163Z","caller":"traceutil/trace.go:171","msg":"trace[1929160683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"526.648843ms","start":"2026-04-20T20:58:35.012487Z","end":"2026-04-20T20:58:35.539136Z","steps":["trace[1929160683] 'agreement among raft nodes before linearized reading' (duration: 526.319155ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:35.539214Z","caller":"traceutil/trace.go:171","msg":"trace[224193630] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"762.024902ms","start":"2026-04-20T20:58:34.777181Z","end":"2026-04-20T20:58:35.539206Z","steps":["trace[224193630] 'process raft request' (duration: 761.362687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:35.539238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:35.012454Z","time spent":"526.768166ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T20:58:35.539273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:34.777156Z","time spent":"762.076394ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:58:35.539356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.753867ms","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-20T20:58:35.539369Z","caller":"traceutil/trace.go:171","msg":"trace[1903950942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"509.785738ms","start":"2026-04-20T20:58:35.029579Z","end":"2026-04-20T20:58:35.539364Z","steps":["trace[1903950942] 'agreement among raft nodes before linearized reading' (duration: 509.744307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:35.539381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:35.029567Z","time spent":"509.810848ms","remote":"127.0.0.1:40924","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-20T20:58:36.204669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.811185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:36.204759Z","caller":"traceutil/trace.go:171","msg":"trace[105191523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"111.986388ms","start":"2026-04-20T20:58:36.092751Z","end":"2026-04-20T20:58:36.204738Z","steps":["trace[105191523] 'range keys from in-memory index tree' (duration: 111.672731ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:37.059326Z","caller":"traceutil/trace.go:171","msg":"trace[1670715842] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"101.203169ms","start":"2026-04-20T20:58:36.958097Z","end":"2026-04-20T20:58:37.059301Z","steps":["trace[1670715842] 'process raft request' (duration: 69.051518ms)","trace[1670715842] 'compare' (duration: 32.053309ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:37.752683Z","caller":"traceutil/trace.go:171","msg":"trace[1674782596] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"203.833273ms","start":"2026-04-20T20:58:37.548824Z","end":"2026-04-20T20:58:37.752657Z","steps":["trace[1674782596] 'read index received' (duration: 200.41794ms)","trace[1674782596] 'applied index is now lower than readState.Index' (duration: 3.413543ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:37.752943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.09435ms","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-20T20:58:37.75302Z","caller":"traceutil/trace.go:171","msg":"trace[1790600642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"204.233223ms","start":"2026-04-20T20:58:37.548769Z","end":"2026-04-20T20:58:37.753003Z","steps":["trace[1790600642] 'agreement among raft nodes before linearized reading' (duration: 204.009838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:37.753256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.951426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T20:58:37.75383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.519319ms","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-20T20:58:37.753875Z","caller":"traceutil/trace.go:171","msg":"trace[1982000610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"204.606071ms","start":"2026-04-20T20:58:37.549259Z","end":"2026-04-20T20:58:37.753865Z","steps":["trace[1982000610] 'agreement among raft nodes before linearized reading' (duration: 204.501779ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:37.753405Z","caller":"traceutil/trace.go:171","msg":"trace[676436018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"204.181002ms","start":"2026-04-20T20:58:37.549202Z","end":"2026-04-20T20:58:37.753383Z","steps":["trace[676436018] 'agreement among raft nodes before linearized reading' (duration: 203.599468ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:41.208694Z","caller":"traceutil/trace.go:171","msg":"trace[1711864045] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"206.706822ms","start":"2026-04-20T20:58:41.001965Z","end":"2026-04-20T20:58:41.208672Z","steps":["trace[1711864045] 'read index received' (duration: 149.029828ms)","trace[1711864045] 'applied index is now lower than readState.Index' (duration: 57.675974ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:41.208723Z","caller":"traceutil/trace.go:171","msg":"trace[2029797830] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"214.614281ms","start":"2026-04-20T20:58:40.994072Z","end":"2026-04-20T20:58:41.208687Z","steps":["trace[2029797830] 'process raft request' (duration: 156.920537ms)","trace[2029797830] 'compare' (duration: 57.565831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:41.208963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.974859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-20T20:58:41.20901Z","caller":"traceutil/trace.go:171","msg":"trace[17271257] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:693; }","duration":"207.070151ms","start":"2026-04-20T20:58:41.001927Z","end":"2026-04-20T20:58:41.208997Z","steps":["trace[17271257] 'agreement among raft nodes before linearized reading' (duration: 206.883256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:41.2094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.430115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:41.209515Z","caller":"traceutil/trace.go:171","msg":"trace[516883274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"116.767923ms","start":"2026-04-20T20:58:41.0927Z","end":"2026-04-20T20:58:41.209468Z","steps":["trace[516883274] 'agreement among raft nodes before linearized reading' (duration: 116.427214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:42.566373Z","caller":"traceutil/trace.go:171","msg":"trace[848179635] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"787.588286ms","start":"2026-04-20T20:58:41.77876Z","end":"2026-04-20T20:58:42.566348Z","steps":["trace[848179635] 'process raft request' (duration: 787.361571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.56654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:41.778745Z","time spent":"787.708439ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:58:42.566795Z","caller":"traceutil/trace.go:171","msg":"trace[1687841950] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:722; }","duration":"740.673209ms","start":"2026-04-20T20:58:41.826069Z","end":"2026-04-20T20:58:42.566742Z","steps":["trace[1687841950] 'read index received' (duration: 50.676526ms)","trace[1687841950] 'applied index is now lower than readState.Index' (duration: 689.995523ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:42.56695Z","caller":"traceutil/trace.go:171","msg":"trace[766237507] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"729.479841ms","start":"2026-04-20T20:58:41.837458Z","end":"2026-04-20T20:58:42.566938Z","steps":["trace[766237507] 'process raft request' (duration: 729.151533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:41.837425Z","time spent":"729.556483ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:58:42.56704Z","caller":"traceutil/trace.go:171","msg":"trace[1932362946] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"788.203211ms","start":"2026-04-20T20:58:41.778817Z","end":"2026-04-20T20:58:42.56702Z","steps":["trace[1932362946] 'process raft request' (duration: 787.494224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.160262ms","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-20T20:58:42.567252Z","caller":"traceutil/trace.go:171","msg":"trace[1405424998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"741.222153ms","start":"2026-04-20T20:58:41.826017Z","end":"2026-04-20T20:58:42.567239Z","steps":["trace[1405424998] 'agreement among raft nodes before linearized reading' (duration: 741.150062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:41.825996Z","time spent":"741.281794ms","remote":"127.0.0.1:40924","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-20T20:58:42.567383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:41.778811Z","time spent":"788.361455ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:58:42.567455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.810339ms","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-20T20:58:42.567491Z","caller":"traceutil/trace.go:171","msg":"trace[316987230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:703; }","duration":"220.872122ms","start":"2026-04-20T20:58:42.34661Z","end":"2026-04-20T20:58:42.567482Z","steps":["trace[316987230] 'agreement among raft nodes before linearized reading' (duration: 220.79265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.594207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfqgh\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-04-20T20:58:42.567557Z","caller":"traceutil/trace.go:171","msg":"trace[1220951477] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfqgh; range_end:; response_count:1; response_revision:703; }","duration":"571.064397ms","start":"2026-04-20T20:58:41.996478Z","end":"2026-04-20T20:58:42.567542Z","steps":["trace[1220951477] 'agreement among raft nodes before linearized reading' (duration: 570.530205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:41.996457Z","time spent":"571.134901ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18538,"request content":"key:\"/registry/pods/kube-system/cilium-dfqgh\" "} {"level":"warn","ts":"2026-04-20T20:58:42.567663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.798727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.142\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T20:58:42.56769Z","caller":"traceutil/trace.go:171","msg":"trace[632716574] range","detail":"{range_begin:/registry/masterleases/199.19.213.142; range_end:; response_count:1; response_revision:703; }","duration":"344.855528ms","start":"2026-04-20T20:58:42.222827Z","end":"2026-04-20T20:58:42.567682Z","steps":["trace[632716574] 'agreement among raft nodes before linearized reading' (duration: 344.772286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:42.222808Z","time spent":"344.90897ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.142\" "} {"level":"warn","ts":"2026-04-20T20:58:42.567751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.071214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-20T20:58:42.567793Z","caller":"traceutil/trace.go:171","msg":"trace[1378992418] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:703; }","duration":"565.143566ms","start":"2026-04-20T20:58:42.002636Z","end":"2026-04-20T20:58:42.56778Z","steps":["trace[1378992418] 'agreement among raft nodes before linearized reading' (duration: 565.000373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.567824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:42.002619Z","time spent":"565.198667ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-20T20:58:42.567979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.418227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-20T20:58:42.56799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.400588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-20T20:58:42.568024Z","caller":"traceutil/trace.go:171","msg":"trace[1045960546] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:703; }","duration":"364.47586ms","start":"2026-04-20T20:58:42.203538Z","end":"2026-04-20T20:58:42.568014Z","steps":["trace[1045960546] 'agreement among raft nodes before linearized reading' (duration: 364.365756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.56805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:42.203515Z","time spent":"364.529161ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188517,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-20T20:58:42.568021Z","caller":"traceutil/trace.go:171","msg":"trace[509242420] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:703; }","duration":"486.460198ms","start":"2026-04-20T20:58:42.08155Z","end":"2026-04-20T20:58:42.568011Z","steps":["trace[509242420] 'agreement among raft nodes before linearized reading' (duration: 486.388065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:42.568141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:42.081532Z","time spent":"486.602241ms","remote":"127.0.0.1:40744","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":"info","ts":"2026-04-20T20:58:46.693287Z","caller":"traceutil/trace.go:171","msg":"trace[752642581] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"107.090901ms","start":"2026-04-20T20:58:46.586171Z","end":"2026-04-20T20:58:46.693262Z","steps":["trace[752642581] 'process raft request' (duration: 106.938987ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:46.802976Z","caller":"traceutil/trace.go:171","msg":"trace[1442996726] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"215.464103ms","start":"2026-04-20T20:58:46.587479Z","end":"2026-04-20T20:58:46.802944Z","steps":["trace[1442996726] 'process raft request' (duration: 189.191673ms)","trace[1442996726] 'compare' (duration: 25.982643ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:47.777521Z","caller":"traceutil/trace.go:171","msg":"trace[919084233] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"426.98888ms","start":"2026-04-20T20:58:47.350509Z","end":"2026-04-20T20:58:47.777498Z","steps":["trace[919084233] 'process raft request' (duration: 426.786305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:47.777674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:47.350484Z","time spent":"427.105312ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:58:47.917228Z","caller":"traceutil/trace.go:171","msg":"trace[1661874899] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"134.906418ms","start":"2026-04-20T20:58:47.782299Z","end":"2026-04-20T20:58:47.917206Z","steps":["trace[1661874899] 'read index received' (duration: 45.070461ms)","trace[1661874899] 'applied index is now lower than readState.Index' (duration: 89.833987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:47.917397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.081403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dfqgh\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-04-20T20:58:47.917429Z","caller":"traceutil/trace.go:171","msg":"trace[589633447] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dfqgh; range_end:; response_count:1; response_revision:741; }","duration":"135.163795ms","start":"2026-04-20T20:58:47.782255Z","end":"2026-04-20T20:58:47.917419Z","steps":["trace[589633447] 'agreement among raft nodes before linearized reading' (duration: 135.082513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:48.644222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.838808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:58:48.644342Z","caller":"traceutil/trace.go:171","msg":"trace[442469969] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"552.72321ms","start":"2026-04-20T20:58:48.091603Z","end":"2026-04-20T20:58:48.644326Z","steps":["trace[442469969] 'read index received' (duration: 392.631745ms)","trace[442469969] 'applied index is now lower than readState.Index' (duration: 160.090355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:48.644668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.059217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:48.644666Z","caller":"traceutil/trace.go:171","msg":"trace[1616329372] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"713.321315ms","start":"2026-04-20T20:58:47.931308Z","end":"2026-04-20T20:58:48.64463Z","steps":["trace[1616329372] 'process raft request' (duration: 552.903093ms)","trace[1616329372] 'compare' (duration: 159.725334ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:48.644721Z","caller":"traceutil/trace.go:171","msg":"trace[1537429726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"553.14398ms","start":"2026-04-20T20:58:48.091562Z","end":"2026-04-20T20:58:48.644706Z","steps":["trace[1537429726] 'agreement among raft nodes before linearized reading' (duration: 552.994066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:48.644846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:47.931297Z","time spent":"713.449328ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:58:48.644955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:48.091545Z","time spent":"553.309313ms","remote":"127.0.0.1:40642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:58:48.803862Z","caller":"traceutil/trace.go:171","msg":"trace[1084848609] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"150.141914ms","start":"2026-04-20T20:58:48.653618Z","end":"2026-04-20T20:58:48.80376Z","steps":["trace[1084848609] 'process raft request' (duration: 149.388806ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:48.91786Z","caller":"traceutil/trace.go:171","msg":"trace[102162785] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:769; }","duration":"221.090318ms","start":"2026-04-20T20:58:48.696746Z","end":"2026-04-20T20:58:48.917836Z","steps":["trace[102162785] 'read index received' (duration: 106.351493ms)","trace[102162785] 'applied index is now lower than readState.Index' (duration: 114.737865ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:48.917934Z","caller":"traceutil/trace.go:171","msg":"trace[865476615] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"260.614646ms","start":"2026-04-20T20:58:48.657295Z","end":"2026-04-20T20:58:48.91791Z","steps":["trace[865476615] 'process raft request' (duration: 258.439044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:48.918061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.294752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T20:58:48.918101Z","caller":"traceutil/trace.go:171","msg":"trace[1192214552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"221.381535ms","start":"2026-04-20T20:58:48.696709Z","end":"2026-04-20T20:58:48.918091Z","steps":["trace[1192214552] 'agreement among raft nodes before linearized reading' (duration: 221.239181ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:48.998837Z","caller":"traceutil/trace.go:171","msg":"trace[1109737263] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"189.403648ms","start":"2026-04-20T20:58:48.809369Z","end":"2026-04-20T20:58:48.998773Z","steps":["trace[1109737263] 'process raft request' (duration: 189.204243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:48.998892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.455356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T20:58:48.999061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.315965ms","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-20T20:58:48.999082Z","caller":"traceutil/trace.go:171","msg":"trace[2136168357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"191.694442ms","start":"2026-04-20T20:58:48.807369Z","end":"2026-04-20T20:58:48.999064Z","steps":["trace[2136168357] 'agreement among raft nodes before linearized reading' (duration: 191.369884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:48.999305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.028334ms","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-20T20:58:48.999153Z","caller":"traceutil/trace.go:171","msg":"trace[1169771598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"184.436038ms","start":"2026-04-20T20:58:48.814685Z","end":"2026-04-20T20:58:48.999121Z","steps":["trace[1169771598] 'agreement among raft nodes before linearized reading' (duration: 184.199922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:48.999382Z","caller":"traceutil/trace.go:171","msg":"trace[1413262345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"178.194897ms","start":"2026-04-20T20:58:48.82117Z","end":"2026-04-20T20:58:48.999365Z","steps":["trace[1413262345] 'agreement among raft nodes before linearized reading' (duration: 177.685725ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:49.163039Z","caller":"traceutil/trace.go:171","msg":"trace[578439864] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"159.120641ms","start":"2026-04-20T20:58:49.003882Z","end":"2026-04-20T20:58:49.163002Z","steps":["trace[578439864] 'process raft request' (duration: 158.764552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:49.163484Z","caller":"traceutil/trace.go:171","msg":"trace[1930686635] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"159.336945ms","start":"2026-04-20T20:58:49.004124Z","end":"2026-04-20T20:58:49.163461Z","steps":["trace[1930686635] 'process raft request' (duration: 159.135761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:49.16355Z","caller":"traceutil/trace.go:171","msg":"trace[33326756] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"159.472718ms","start":"2026-04-20T20:58:49.004061Z","end":"2026-04-20T20:58:49.163534Z","steps":["trace[33326756] 'process raft request' (duration: 158.804992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:55.571436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539277749734000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:58:55.830278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263284027s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:58:55.83046Z","caller":"traceutil/trace.go:171","msg":"trace[1401947725] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"1.78954346s","start":"2026-04-20T20:58:54.040896Z","end":"2026-04-20T20:58:55.830439Z","steps":["trace[1401947725] 'process raft request' (duration: 526.003658ms)","trace[1401947725] 'compare' (duration: 1.262206189s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:58:55.830558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:54.040886Z","time spent":"1.789631762s","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:58:55.92702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.36010954s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T20:58:55.927426Z","caller":"traceutil/trace.go:171","msg":"trace[1851137955] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"856.857649ms","start":"2026-04-20T20:58:55.070536Z","end":"2026-04-20T20:58:55.927394Z","steps":["trace[1851137955] 'read index received' (duration: 856.613313ms)","trace[1851137955] 'applied index is now lower than readState.Index' (duration: 243.376µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:55.92753Z","caller":"traceutil/trace.go:171","msg":"trace[1625790076] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.882299076s","start":"2026-04-20T20:58:54.045183Z","end":"2026-04-20T20:58:55.927482Z","steps":["trace[1625790076] 'process raft request' (duration: 1.88205125s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:55.927723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.158616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T20:58:55.927747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.816056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:55.927793Z","caller":"traceutil/trace.go:171","msg":"trace[1622675335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"857.28077ms","start":"2026-04-20T20:58:55.070495Z","end":"2026-04-20T20:58:55.927776Z","steps":["trace[1622675335] 'agreement among raft nodes before linearized reading' (duration: 857.007002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:55.9278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:54.045168Z","time spent":"1.882500251s","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:58:55.927801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.81539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-20T20:58:55.927865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:55.070475Z","time spent":"857.375792ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T20:58:55.927889Z","caller":"traceutil/trace.go:171","msg":"trace[626607491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"744.928503ms","start":"2026-04-20T20:58:55.182945Z","end":"2026-04-20T20:58:55.927874Z","steps":["trace[626607491] 'agreement among raft nodes before linearized reading' (duration: 744.748068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:55.927903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.732712ms","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-20T20:58:55.927889Z","caller":"traceutil/trace.go:171","msg":"trace[45427039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"835.892547ms","start":"2026-04-20T20:58:55.09189Z","end":"2026-04-20T20:58:55.927782Z","steps":["trace[45427039] 'agreement among raft nodes before linearized reading' (duration: 835.699711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:55.928002Z","caller":"traceutil/trace.go:171","msg":"trace[1782243848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"740.844295ms","start":"2026-04-20T20:58:55.187122Z","end":"2026-04-20T20:58:55.927966Z","steps":["trace[1782243848] 'agreement among raft nodes before linearized reading' (duration: 740.61633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:55.928036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:55.091876Z","time spent":"836.147823ms","remote":"127.0.0.1:40642","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-20T20:58:55.928055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:55.187111Z","time spent":"740.935627ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T20:58:55.927931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:55.182931Z","time spent":"744.988024ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T20:58:55.928181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.630406ms","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-20T20:58:55.929509Z","caller":"traceutil/trace.go:171","msg":"trace[2129164610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"745.992698ms","start":"2026-04-20T20:58:55.183497Z","end":"2026-04-20T20:58:55.92949Z","steps":["trace[2129164610] 'agreement among raft nodes before linearized reading' (duration: 744.524163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:55.929572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:55.183476Z","time spent":"746.08067ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T20:58:56.281525Z","caller":"traceutil/trace.go:171","msg":"trace[578791032] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"117.45901ms","start":"2026-04-20T20:58:56.164044Z","end":"2026-04-20T20:58:56.281503Z","steps":["trace[578791032] 'process raft request' (duration: 117.354787ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:56.350863Z","caller":"traceutil/trace.go:171","msg":"trace[84787623] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"126.156908ms","start":"2026-04-20T20:58:56.22467Z","end":"2026-04-20T20:58:56.350826Z","steps":["trace[84787623] 'process raft request' (duration: 125.938272ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:58.400262Z","caller":"traceutil/trace.go:171","msg":"trace[1207047866] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"176.834425ms","start":"2026-04-20T20:58:58.223401Z","end":"2026-04-20T20:58:58.400235Z","steps":["trace[1207047866] 'process raft request' (duration: 176.6252ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:16.355287Z","caller":"traceutil/trace.go:171","msg":"trace[1658541712] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"136.017574ms","start":"2026-04-20T20:59:16.219248Z","end":"2026-04-20T20:59:16.355265Z","steps":["trace[1658541712] 'process raft request' (duration: 105.764849ms)","trace[1658541712] 'compare' (duration: 30.149543ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:18.348246Z","caller":"traceutil/trace.go:171","msg":"trace[691731360] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"129.343914ms","start":"2026-04-20T20:59:18.218877Z","end":"2026-04-20T20:59:18.348221Z","steps":["trace[691731360] 'read index received' (duration: 129.106759ms)","trace[691731360] 'applied index is now lower than readState.Index' (duration: 236.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:18.348433Z","caller":"traceutil/trace.go:171","msg":"trace[1937329183] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"175.341589ms","start":"2026-04-20T20:59:18.172937Z","end":"2026-04-20T20:59:18.348279Z","steps":["trace[1937329183] 'process raft request' (duration: 175.115873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:18.348478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.58583ms","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-20T20:59:18.348532Z","caller":"traceutil/trace.go:171","msg":"trace[1616983826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"129.692222ms","start":"2026-04-20T20:59:18.218825Z","end":"2026-04-20T20:59:18.348517Z","steps":["trace[1616983826] 'agreement among raft nodes before linearized reading' (duration: 129.515708ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:20.488622Z","caller":"traceutil/trace.go:171","msg":"trace[666650220] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"126.976678ms","start":"2026-04-20T20:59:20.361616Z","end":"2026-04-20T20:59:20.488592Z","steps":["trace[666650220] 'read index received' (duration: 126.686611ms)","trace[666650220] 'applied index is now lower than readState.Index' (duration: 288.977µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:20.489273Z","caller":"traceutil/trace.go:171","msg":"trace[880894890] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"132.249513ms","start":"2026-04-20T20:59:20.35701Z","end":"2026-04-20T20:59:20.48926Z","steps":["trace[880894890] 'process raft request' (duration: 131.399483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:20.489548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.9286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T20:59:20.489591Z","caller":"traceutil/trace.go:171","msg":"trace[340222162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"128.014372ms","start":"2026-04-20T20:59:20.361564Z","end":"2026-04-20T20:59:20.489578Z","steps":["trace[340222162] 'agreement among raft nodes before linearized reading' (duration: 127.875239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:20.489775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.699956ms","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-20T20:59:20.489822Z","caller":"traceutil/trace.go:171","msg":"trace[1945423778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"125.768409ms","start":"2026-04-20T20:59:20.364045Z","end":"2026-04-20T20:59:20.489813Z","steps":["trace[1945423778] 'agreement among raft nodes before linearized reading' (duration: 125.671906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:20.489948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.935933ms","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-20T20:59:20.489971Z","caller":"traceutil/trace.go:171","msg":"trace[2006200209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"120.977883ms","start":"2026-04-20T20:59:20.368986Z","end":"2026-04-20T20:59:20.489964Z","steps":["trace[2006200209] 'agreement among raft nodes before linearized reading' (duration: 120.909061ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:22.695089Z","caller":"traceutil/trace.go:171","msg":"trace[1811669655] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"116.357213ms","start":"2026-04-20T20:59:22.578704Z","end":"2026-04-20T20:59:22.695061Z","steps":["trace[1811669655] 'process raft request' (duration: 84.320513ms)","trace[1811669655] 'compare' (duration: 31.917607ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:22.867798Z","caller":"traceutil/trace.go:171","msg":"trace[1546177068] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"288.34698ms","start":"2026-04-20T20:59:22.579426Z","end":"2026-04-20T20:59:22.867773Z","steps":["trace[1546177068] 'process raft request' (duration: 222.422999ms)","trace[1546177068] 'compare' (duration: 65.741847ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:22.86795Z","caller":"traceutil/trace.go:171","msg":"trace[1692066850] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"218.524745ms","start":"2026-04-20T20:59:22.649218Z","end":"2026-04-20T20:59:22.867742Z","steps":["trace[1692066850] 'process raft request' (duration: 218.485614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:29.120791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.343298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-20T20:59:29.12095Z","caller":"traceutil/trace.go:171","msg":"trace[399106345] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:988; }","duration":"115.608874ms","start":"2026-04-20T20:59:29.005321Z","end":"2026-04-20T20:59:29.12093Z","steps":["trace[399106345] 'range keys from in-memory index tree' (duration: 115.232996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:29.265041Z","caller":"traceutil/trace.go:171","msg":"trace[1402046935] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"133.915554ms","start":"2026-04-20T20:59:29.131102Z","end":"2026-04-20T20:59:29.265017Z","steps":["trace[1402046935] 'process raft request' (duration: 133.766251ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:29.386378Z","caller":"traceutil/trace.go:171","msg":"trace[757612081] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"110.672025ms","start":"2026-04-20T20:59:29.275683Z","end":"2026-04-20T20:59:29.386355Z","steps":["trace[757612081] 'process raft request' (duration: 107.5563ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:30.795529Z","caller":"traceutil/trace.go:171","msg":"trace[334226884] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"176.759662ms","start":"2026-04-20T20:59:30.618746Z","end":"2026-04-20T20:59:30.795506Z","steps":["trace[334226884] 'read index received' (duration: 176.555907ms)","trace[334226884] 'applied index is now lower than readState.Index' (duration: 202.555µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:30.795662Z","caller":"traceutil/trace.go:171","msg":"trace[1950696972] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"221.796323ms","start":"2026-04-20T20:59:30.573831Z","end":"2026-04-20T20:59:30.795627Z","steps":["trace[1950696972] 'process raft request' (duration: 221.532817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:30.795927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.162201ms","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-20T20:59:30.795989Z","caller":"traceutil/trace.go:171","msg":"trace[688647869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"177.285394ms","start":"2026-04-20T20:59:30.618688Z","end":"2026-04-20T20:59:30.795974Z","steps":["trace[688647869] 'agreement among raft nodes before linearized reading' (duration: 176.908535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:31.440987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539277749734453,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:59:31.929104Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.090141644s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T20:59:31.929502Z","caller":"traceutil/trace.go:171","msg":"trace[240716109] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.090611205s","start":"2026-04-20T20:59:30.838868Z","end":"2026-04-20T20:59:31.92948Z","steps":["trace[240716109] 'process raft request' (duration: 1.090470802s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:31.929616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:30.838857Z","time spent":"1.090688067s","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:32.856855Z","caller":"traceutil/trace.go:171","msg":"trace[229057968] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"403.74136ms","start":"2026-04-20T20:59:32.453089Z","end":"2026-04-20T20:59:32.85683Z","steps":["trace[229057968] 'process raft request' (duration: 403.662448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:32.857003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.453058Z","time spent":"403.868323ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:32.857291Z","caller":"traceutil/trace.go:171","msg":"trace[713146714] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"1.917019679s","start":"2026-04-20T20:59:30.940248Z","end":"2026-04-20T20:59:32.857267Z","steps":["trace[713146714] 'read index received' (duration: 989.1334ms)","trace[713146714] 'applied index is now lower than readState.Index' (duration: 927.883999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:32.857477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765462573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:32.857449Z","caller":"traceutil/trace.go:171","msg":"trace[860737443] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.947241546s","start":"2026-04-20T20:59:30.910161Z","end":"2026-04-20T20:59:32.857402Z","steps":["trace[860737443] 'process raft request' (duration: 1.873386863s)","trace[860737443] 'compare' (duration: 72.695315ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:32.857527Z","caller":"traceutil/trace.go:171","msg":"trace[126133908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"1.765558304s","start":"2026-04-20T20:59:31.091956Z","end":"2026-04-20T20:59:32.857514Z","steps":["trace[126133908] 'agreement among raft nodes before linearized reading' (duration: 1.765464772s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:32.857562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:31.091934Z","time spent":"1.765619556s","remote":"127.0.0.1:40642","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-20T20:59:32.857541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917280626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T20:59:32.857622Z","caller":"traceutil/trace.go:171","msg":"trace[1672816120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.917414329s","start":"2026-04-20T20:59:30.940188Z","end":"2026-04-20T20:59:32.857602Z","steps":["trace[1672816120] 'agreement among raft nodes before linearized reading' (duration: 1.917185574s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:32.857664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:30.940173Z","time spent":"1.917481101s","remote":"127.0.0.1:40924","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-20T20:59:32.857655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:30.910137Z","time spent":"1.94740893s","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:59:32.857731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.578558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.142\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T20:59:32.857776Z","caller":"traceutil/trace.go:171","msg":"trace[2137506928] range","detail":"{range_begin:/registry/masterleases/199.19.213.142; range_end:; response_count:1; response_revision:998; }","duration":"631.66861ms","start":"2026-04-20T20:59:32.226096Z","end":"2026-04-20T20:59:32.857764Z","steps":["trace[2137506928] 'agreement among raft nodes before linearized reading' (duration: 631.547187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:32.857785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.896896ms","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-20T20:59:32.857803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.226062Z","time spent":"631.734512ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.142\" "} {"level":"info","ts":"2026-04-20T20:59:32.857819Z","caller":"traceutil/trace.go:171","msg":"trace[2008679124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"741.962438ms","start":"2026-04-20T20:59:32.115845Z","end":"2026-04-20T20:59:32.857807Z","steps":["trace[2008679124] 'agreement among raft nodes before linearized reading' (duration: 741.871686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:32.857846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.11583Z","time spent":"742.010609ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T20:59:33.505404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.029306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:59:33.505599Z","caller":"traceutil/trace.go:171","msg":"trace[362067919] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"616.900157ms","start":"2026-04-20T20:59:32.888686Z","end":"2026-04-20T20:59:33.505586Z","steps":["trace[362067919] 'process raft request' (duration: 616.872217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:33.505645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.88867Z","time spent":"616.953828ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:33.505657Z","caller":"traceutil/trace.go:171","msg":"trace[504363305] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"643.428364ms","start":"2026-04-20T20:59:32.862215Z","end":"2026-04-20T20:59:33.505643Z","steps":["trace[504363305] 'process raft request' (duration: 643.25969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:33.505737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.862208Z","time spent":"643.502075ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:33.50579Z","caller":"traceutil/trace.go:171","msg":"trace[1368976143] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"643.444914ms","start":"2026-04-20T20:59:32.862339Z","end":"2026-04-20T20:59:33.505784Z","steps":["trace[1368976143] 'process raft request' (duration: 643.175608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:33.505823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.862332Z","time spent":"643.474325ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:33.505866Z","caller":"traceutil/trace.go:171","msg":"trace[2002182627] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"642.770448ms","start":"2026-04-20T20:59:32.863091Z","end":"2026-04-20T20:59:33.505861Z","steps":["trace[2002182627] 'process raft request' (duration: 642.44395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:33.505894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.863084Z","time spent":"642.793838ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:33.50592Z","caller":"traceutil/trace.go:171","msg":"trace[579958677] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"643.779501ms","start":"2026-04-20T20:59:32.862132Z","end":"2026-04-20T20:59:33.505912Z","steps":["trace[579958677] 'process raft request' (duration: 114.18273ms)","trace[579958677] 'compare' (duration: 528.726479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:33.505957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.862112Z","time spent":"643.823663ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:33.505933Z","caller":"traceutil/trace.go:171","msg":"trace[1668724685] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1035; }","duration":"639.969131ms","start":"2026-04-20T20:59:32.865938Z","end":"2026-04-20T20:59:33.505907Z","steps":["trace[1668724685] 'read index received' (duration: 22.142752ms)","trace[1668724685] 'applied index is now lower than readState.Index' (duration: 617.823749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:33.506051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.087684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:33.506082Z","caller":"traceutil/trace.go:171","msg":"trace[1048037537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"640.159505ms","start":"2026-04-20T20:59:32.865913Z","end":"2026-04-20T20:59:33.506073Z","steps":["trace[1048037537] 'agreement among raft nodes before linearized reading' (duration: 640.075963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:33.506113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:32.865904Z","time spent":"640.201996ms","remote":"127.0.0.1:40642","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-20T20:59:34.592618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539277749734471,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:59:34.78812Z","caller":"traceutil/trace.go:171","msg":"trace[1106517475] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"695.584755ms","start":"2026-04-20T20:59:34.09249Z","end":"2026-04-20T20:59:34.788074Z","steps":["trace[1106517475] 'read index received' (duration: 695.501683ms)","trace[1106517475] 'applied index is now lower than readState.Index' (duration: 82.051µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:34.78815Z","caller":"traceutil/trace.go:171","msg":"trace[2019589114] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"848.630748ms","start":"2026-04-20T20:59:33.93949Z","end":"2026-04-20T20:59:34.788121Z","steps":["trace[2019589114] 'process raft request' (duration: 848.460664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:34.788251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.735918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:34.788275Z","caller":"traceutil/trace.go:171","msg":"trace[1804940839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"695.81749ms","start":"2026-04-20T20:59:34.09245Z","end":"2026-04-20T20:59:34.788268Z","steps":["trace[1804940839] 'agreement among raft nodes before linearized reading' (duration: 695.724398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:34.788296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:34.092433Z","time spent":"695.858241ms","remote":"127.0.0.1:40642","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-20T20:59:34.788292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:33.939474Z","time spent":"848.743181ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:35.137317Z","caller":"traceutil/trace.go:171","msg":"trace[313202936] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"272.063769ms","start":"2026-04-20T20:59:34.865211Z","end":"2026-04-20T20:59:35.137275Z","steps":["trace[313202936] 'process raft request' (duration: 236.202548ms)","trace[313202936] 'compare' (duration: 35.140084ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:37.259013Z","caller":"traceutil/trace.go:171","msg":"trace[1612460893] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"117.666124ms","start":"2026-04-20T20:59:37.141319Z","end":"2026-04-20T20:59:37.258986Z","steps":["trace[1612460893] 'process raft request' (duration: 18.626447ms)","trace[1612460893] 'compare' (duration: 98.921134ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:37.998765Z","caller":"traceutil/trace.go:171","msg":"trace[1823593036] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"444.391626ms","start":"2026-04-20T20:59:37.554331Z","end":"2026-04-20T20:59:37.998722Z","steps":["trace[1823593036] 'process raft request' (duration: 444.241432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:37.998918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:37.554313Z","time spent":"444.524809ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:38.203071Z","caller":"traceutil/trace.go:171","msg":"trace[1762475494] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"648.598646ms","start":"2026-04-20T20:59:37.554443Z","end":"2026-04-20T20:59:38.203042Z","steps":["trace[1762475494] 'process raft request' (duration: 554.453667ms)","trace[1762475494] 'compare' (duration: 93.891563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:38.203265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:37.554411Z","time spent":"648.757981ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:38.203409Z","caller":"traceutil/trace.go:171","msg":"trace[1662886927] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1050; }","duration":"448.529686ms","start":"2026-04-20T20:59:37.754862Z","end":"2026-04-20T20:59:38.203392Z","steps":["trace[1662886927] 'read index received' (duration: 243.785031ms)","trace[1662886927] 'applied index is now lower than readState.Index' (duration: 204.743955ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:38.203449Z","caller":"traceutil/trace.go:171","msg":"trace[2020120248] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"648.416864ms","start":"2026-04-20T20:59:37.555006Z","end":"2026-04-20T20:59:38.203423Z","steps":["trace[2020120248] 'process raft request' (duration: 647.992212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:38.203507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.633519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T20:59:38.203543Z","caller":"traceutil/trace.go:171","msg":"trace[334680828] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1016; }","duration":"448.736081ms","start":"2026-04-20T20:59:37.754788Z","end":"2026-04-20T20:59:38.203524Z","steps":["trace[334680828] 'agreement among raft nodes before linearized reading' (duration: 448.669189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:38.203573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:37.754758Z","time spent":"448.808243ms","remote":"127.0.0.1:40846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:59:38.203579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:37.55499Z","time spent":"648.522436ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:38.203684Z","caller":"traceutil/trace.go:171","msg":"trace[1109585217] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"648.643219ms","start":"2026-04-20T20:59:37.555033Z","end":"2026-04-20T20:59:38.203676Z","steps":["trace[1109585217] 'process raft request' (duration: 648.241388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:38.203725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:37.554977Z","time spent":"648.72537ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:49.460582Z","caller":"traceutil/trace.go:171","msg":"trace[630670005] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"102.678335ms","start":"2026-04-20T20:59:49.357879Z","end":"2026-04-20T20:59:49.460557Z","steps":["trace[630670005] 'process raft request' (duration: 102.537062ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:53.572849Z","caller":"traceutil/trace.go:171","msg":"trace[952742833] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"101.639859ms","start":"2026-04-20T20:59:53.471179Z","end":"2026-04-20T20:59:53.572819Z","steps":["trace[952742833] 'process raft request' (duration: 97.736526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:57.592893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539277749734660,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T20:59:57.682183Z","caller":"traceutil/trace.go:171","msg":"trace[1125843755] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"691.066457ms","start":"2026-04-20T20:59:56.99109Z","end":"2026-04-20T20:59:57.682156Z","steps":["trace[1125843755] 'process raft request' (duration: 690.887502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:57.682407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:56.991073Z","time spent":"691.1908ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:58.296563Z","caller":"traceutil/trace.go:171","msg":"trace[261931030] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"758.949405ms","start":"2026-04-20T20:59:57.537592Z","end":"2026-04-20T20:59:58.296542Z","steps":["trace[261931030] 'process raft request' (duration: 758.688699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:58.296901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:57.537568Z","time spent":"759.096089ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:58.29735Z","caller":"traceutil/trace.go:171","msg":"trace[2114194079] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"705.366789ms","start":"2026-04-20T20:59:57.591955Z","end":"2026-04-20T20:59:58.297322Z","steps":["trace[2114194079] 'process raft request' (duration: 704.485358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:58.297508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:57.59194Z","time spent":"705.479851ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:59:58.297662Z","caller":"traceutil/trace.go:171","msg":"trace[1228203714] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1116; }","duration":"1.205260348s","start":"2026-04-20T20:59:57.092033Z","end":"2026-04-20T20:59:58.297293Z","steps":["trace[1228203714] 'read index received' (duration: 589.94288ms)","trace[1228203714] 'applied index is now lower than readState.Index' (duration: 615.316288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:58.297742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205739541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:58.297939Z","caller":"traceutil/trace.go:171","msg":"trace[1621908332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"1.205946205s","start":"2026-04-20T20:59:57.091963Z","end":"2026-04-20T20:59:58.297909Z","steps":["trace[1621908332] 'agreement among raft nodes before linearized reading' (duration: 1.2057421s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:58.298514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023539418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T20:59:58.298577Z","caller":"traceutil/trace.go:171","msg":"trace[419004295] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1078; }","duration":"1.023634489s","start":"2026-04-20T20:59:57.274928Z","end":"2026-04-20T20:59:58.298562Z","steps":["trace[419004295] 'agreement among raft nodes before linearized reading' (duration: 1.023535387s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:58.298614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:57.274915Z","time spent":"1.02368968s","remote":"127.0.0.1:40928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-20T20:59:58.29877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:57.091938Z","time spent":"1.206194861s","remote":"127.0.0.1:40642","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-20T20:59:58.298985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.851616ms","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-20T20:59:58.29902Z","caller":"traceutil/trace.go:171","msg":"trace[1133512930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"179.929248ms","start":"2026-04-20T20:59:58.119081Z","end":"2026-04-20T20:59:58.29901Z","steps":["trace[1133512930] 'agreement among raft nodes before linearized reading' (duration: 179.849396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:58.299149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.231538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T20:59:58.299173Z","caller":"traceutil/trace.go:171","msg":"trace[1826457969] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1078; }","duration":"508.29288ms","start":"2026-04-20T20:59:57.790873Z","end":"2026-04-20T20:59:58.299166Z","steps":["trace[1826457969] 'agreement among raft nodes before linearized reading' (duration: 508.206808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:58.2992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:57.790855Z","time spent":"508.33764ms","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":112,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-20T20:59:58.403214Z","caller":"traceutil/trace.go:171","msg":"trace[1556491966] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"100.214515ms","start":"2026-04-20T20:59:58.302975Z","end":"2026-04-20T20:59:58.40319Z","steps":["trace[1556491966] 'process raft request' (duration: 79.57957ms)","trace[1556491966] 'compare' (duration: 20.531823ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:58.424803Z","caller":"traceutil/trace.go:171","msg":"trace[716091433] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"120.777829ms","start":"2026-04-20T20:59:58.304013Z","end":"2026-04-20T20:59:58.424791Z","steps":["trace[716091433] 'process raft request' (duration: 120.429221ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:58.424586Z","caller":"traceutil/trace.go:171","msg":"trace[836431860] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"118.68477ms","start":"2026-04-20T20:59:58.305888Z","end":"2026-04-20T20:59:58.424572Z","steps":["trace[836431860] 'read index received' (duration: 76.774614ms)","trace[836431860] 'applied index is now lower than readState.Index' (duration: 41.909286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:58.425144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.1211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:58.425488Z","caller":"traceutil/trace.go:171","msg":"trace[729297928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"119.326045ms","start":"2026-04-20T20:59:58.305879Z","end":"2026-04-20T20:59:58.425205Z","steps":["trace[729297928] 'agreement among raft nodes before linearized reading' (duration: 119.036928ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:58.558631Z","caller":"traceutil/trace.go:171","msg":"trace[2046299694] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"128.887284ms","start":"2026-04-20T20:59:58.429706Z","end":"2026-04-20T20:59:58.558594Z","steps":["trace[2046299694] 'process raft request' (duration: 78.128355ms)","trace[2046299694] 'compare' (duration: 50.558094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:00.593379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680539277749734675,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T21:00:00.625753Z","caller":"traceutil/trace.go:171","msg":"trace[1108722073] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"934.466759ms","start":"2026-04-20T20:59:59.691256Z","end":"2026-04-20T21:00:00.625722Z","steps":["trace[1108722073] 'process raft request' (duration: 934.283224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:00.626583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:59:59.691239Z","time spent":"934.582451ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:00.641324Z","caller":"traceutil/trace.go:171","msg":"trace[384264304] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"548.582717ms","start":"2026-04-20T21:00:00.092717Z","end":"2026-04-20T21:00:00.6413Z","steps":["trace[384264304] 'read index received' (duration: 532.798449ms)","trace[384264304] 'applied index is now lower than readState.Index' (duration: 15.783198ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:00.641415Z","caller":"traceutil/trace.go:171","msg":"trace[1711013058] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"336.556158ms","start":"2026-04-20T21:00:00.304823Z","end":"2026-04-20T21:00:00.641379Z","steps":["trace[1711013058] 'process raft request' (duration: 336.372394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:00.641626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:00.304795Z","time spent":"336.702781ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T21:00:00.6417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.008011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T21:00:00.641733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.269295ms","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":"warn","ts":"2026-04-20T21:00:00.641657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.993928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:00.641788Z","caller":"traceutil/trace.go:171","msg":"trace[1904363020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1084; }","duration":"235.139474ms","start":"2026-04-20T21:00:00.406624Z","end":"2026-04-20T21:00:00.641763Z","steps":["trace[1904363020] 'agreement among raft nodes before linearized reading' (duration: 234.899788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:00.64176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.413032ms","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-20T21:00:00.641827Z","caller":"traceutil/trace.go:171","msg":"trace[662425818] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1084; }","duration":"172.211143ms","start":"2026-04-20T21:00:00.469585Z","end":"2026-04-20T21:00:00.641796Z","steps":["trace[662425818] 'agreement among raft nodes before linearized reading' (duration: 172.007828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:00.641798Z","caller":"traceutil/trace.go:171","msg":"trace[802595194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"162.374787ms","start":"2026-04-20T21:00:00.479405Z","end":"2026-04-20T21:00:00.64178Z","steps":["trace[802595194] 'agreement among raft nodes before linearized reading' (duration: 162.143142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:00.641921Z","caller":"traceutil/trace.go:171","msg":"trace[453815531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"213.590776ms","start":"2026-04-20T21:00:00.428309Z","end":"2026-04-20T21:00:00.6419Z","steps":["trace[453815531] 'agreement among raft nodes before linearized reading' (duration: 213.31759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:00.642309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.58855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:00.642359Z","caller":"traceutil/trace.go:171","msg":"trace[681352605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"549.680124ms","start":"2026-04-20T21:00:00.092663Z","end":"2026-04-20T21:00:00.642343Z","steps":["trace[681352605] 'agreement among raft nodes before linearized reading' (duration: 548.763421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:00.642536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:00.09264Z","time spent":"549.881279ms","remote":"127.0.0.1:40642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T21:00:00.776182Z","caller":"traceutil/trace.go:171","msg":"trace[320936923] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"128.926665ms","start":"2026-04-20T21:00:00.647223Z","end":"2026-04-20T21:00:00.776149Z","steps":["trace[320936923] 'process raft request' (duration: 68.787231ms)","trace[320936923] 'compare' (duration: 59.956059ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:00.776502Z","caller":"traceutil/trace.go:171","msg":"trace[49096593] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"129.232632ms","start":"2026-04-20T21:00:00.647257Z","end":"2026-04-20T21:00:00.77649Z","steps":["trace[49096593] 'process raft request' (duration: 128.853313ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:00.776558Z","caller":"traceutil/trace.go:171","msg":"trace[413118957] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"129.215381ms","start":"2026-04-20T21:00:00.647311Z","end":"2026-04-20T21:00:00.776527Z","steps":["trace[413118957] 'process raft request' (duration: 129.005897ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:02.816553Z","caller":"traceutil/trace.go:171","msg":"trace[366623427] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"390.145834ms","start":"2026-04-20T21:00:02.426383Z","end":"2026-04-20T21:00:02.816529Z","steps":["trace[366623427] 'read index received' (duration: 389.992321ms)","trace[366623427] 'applied index is now lower than readState.Index' (duration: 151.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:02.816752Z","caller":"traceutil/trace.go:171","msg":"trace[224644404] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"546.443745ms","start":"2026-04-20T21:00:02.270146Z","end":"2026-04-20T21:00:02.81659Z","steps":["trace[224644404] 'process raft request' (duration: 546.187109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:02.816846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.395141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:02.816977Z","caller":"traceutil/trace.go:171","msg":"trace[695586434] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1089; }","duration":"390.601406ms","start":"2026-04-20T21:00:02.42633Z","end":"2026-04-20T21:00:02.816932Z","steps":["trace[695586434] 'agreement among raft nodes before linearized reading' (duration: 390.316339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:02.81706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:02.42631Z","time spent":"390.707528ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T21:00:02.817205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:02.27013Z","time spent":"546.788734ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:02.825344Z","caller":"traceutil/trace.go:171","msg":"trace[356597177] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"177.379818ms","start":"2026-04-20T21:00:02.647941Z","end":"2026-04-20T21:00:02.825321Z","steps":["trace[356597177] 'process raft request' (duration: 177.186284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:02.825578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.405435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:02.825619Z","caller":"traceutil/trace.go:171","msg":"trace[372893239] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1090; }","duration":"201.552808ms","start":"2026-04-20T21:00:02.624056Z","end":"2026-04-20T21:00:02.825609Z","steps":["trace[372893239] 'agreement among raft nodes before linearized reading' (duration: 201.464076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:02.825676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.467137ms","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-20T21:00:02.825713Z","caller":"traceutil/trace.go:171","msg":"trace[833892342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"194.545429ms","start":"2026-04-20T21:00:02.631159Z","end":"2026-04-20T21:00:02.825705Z","steps":["trace[833892342] 'agreement among raft nodes before linearized reading' (duration: 194.357735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:02.82578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.93321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:02.825847Z","caller":"traceutil/trace.go:171","msg":"trace[1772982341] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1090; }","duration":"310.051071ms","start":"2026-04-20T21:00:02.51578Z","end":"2026-04-20T21:00:02.825831Z","steps":["trace[1772982341] 'agreement among raft nodes before linearized reading' (duration: 309.945549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:02.825884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:02.51576Z","time spent":"310.114923ms","remote":"127.0.0.1:40736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T21:00:02.825896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.622932ms","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-20T21:00:02.825925Z","caller":"traceutil/trace.go:171","msg":"trace[612416166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"169.683783ms","start":"2026-04-20T21:00:02.656233Z","end":"2026-04-20T21:00:02.825916Z","steps":["trace[612416166] 'agreement among raft nodes before linearized reading' (duration: 169.579911ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:02.961514Z","caller":"traceutil/trace.go:171","msg":"trace[204849264] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"132.156192ms","start":"2026-04-20T21:00:02.829329Z","end":"2026-04-20T21:00:02.961485Z","steps":["trace[204849264] 'process raft request' (duration: 109.58527ms)","trace[204849264] 'compare' (duration: 22.419098ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:02.983385Z","caller":"traceutil/trace.go:171","msg":"trace[222536832] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"152.461239ms","start":"2026-04-20T21:00:02.830907Z","end":"2026-04-20T21:00:02.983368Z","steps":["trace[222536832] 'process raft request' (duration: 152.417678ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:02.983386Z","caller":"traceutil/trace.go:171","msg":"trace[1822908254] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"153.430173ms","start":"2026-04-20T21:00:02.82993Z","end":"2026-04-20T21:00:02.98336Z","steps":["trace[1822908254] 'process raft request' (duration: 153.204417ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:02.983819Z","caller":"traceutil/trace.go:171","msg":"trace[1793303115] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"153.584156ms","start":"2026-04-20T21:00:02.83016Z","end":"2026-04-20T21:00:02.983744Z","steps":["trace[1793303115] 'process raft request' (duration: 153.101334ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:02.983823Z","caller":"traceutil/trace.go:171","msg":"trace[1875505046] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"153.439522ms","start":"2026-04-20T21:00:02.830367Z","end":"2026-04-20T21:00:02.983806Z","steps":["trace[1875505046] 'process raft request' (duration: 152.92811ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:13.832036Z","caller":"traceutil/trace.go:171","msg":"trace[1052498848] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"111.166612ms","start":"2026-04-20T21:00:13.720834Z","end":"2026-04-20T21:00:13.832Z","steps":["trace[1052498848] 'process raft request' (duration: 44.268884ms)","trace[1052498848] 'compare' (duration: 66.741934ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:17.122089Z","caller":"traceutil/trace.go:171","msg":"trace[1723504345] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"106.407657ms","start":"2026-04-20T21:00:17.015657Z","end":"2026-04-20T21:00:17.122065Z","steps":["trace[1723504345] 'process raft request' (duration: 106.270964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:19.177526Z","caller":"traceutil/trace.go:171","msg":"trace[1709426016] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"307.231954ms","start":"2026-04-20T21:00:18.870271Z","end":"2026-04-20T21:00:19.177503Z","steps":["trace[1709426016] 'process raft request' (duration: 307.093881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.177657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:18.870251Z","time spent":"307.338696ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T21:00:19.657504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.777512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T21:00:19.657623Z","caller":"traceutil/trace.go:171","msg":"trace[296351063] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"562.116488ms","start":"2026-04-20T21:00:19.095485Z","end":"2026-04-20T21:00:19.657601Z","steps":["trace[296351063] 'read index received' (duration: 81.867897ms)","trace[296351063] 'applied index is now lower than readState.Index' (duration: 480.246891ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:19.657727Z","caller":"traceutil/trace.go:171","msg":"trace[492994995] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"742.728549ms","start":"2026-04-20T21:00:18.914986Z","end":"2026-04-20T21:00:19.657714Z","steps":["trace[492994995] 'process raft request' (duration: 383.6637ms)","trace[492994995] 'compare' (duration: 358.636358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:19.657762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.165929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:19.657815Z","caller":"traceutil/trace.go:171","msg":"trace[90110942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"562.253881ms","start":"2026-04-20T21:00:19.095549Z","end":"2026-04-20T21:00:19.657803Z","steps":["trace[90110942] 'agreement among raft nodes before linearized reading' (duration: 562.172179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.657839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.095536Z","time spent":"562.299072ms","remote":"127.0.0.1:40646","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-20T21:00:19.657887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.403695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:19.657908Z","caller":"traceutil/trace.go:171","msg":"trace[1312458926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"562.458336ms","start":"2026-04-20T21:00:19.095444Z","end":"2026-04-20T21:00:19.657903Z","steps":["trace[1312458926] 'agreement among raft nodes before linearized reading' (duration: 562.431415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.657922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.095426Z","time spent":"562.490836ms","remote":"127.0.0.1:40642","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-20T21:00:19.658077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.70889ms","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-20T21:00:19.65809Z","caller":"traceutil/trace.go:171","msg":"trace[822329938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1203; }","duration":"457.74166ms","start":"2026-04-20T21:00:19.200344Z","end":"2026-04-20T21:00:19.658086Z","steps":["trace[822329938] 'agreement among raft nodes before linearized reading' (duration: 457.682178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.658102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.200334Z","time spent":"457.765792ms","remote":"127.0.0.1:40924","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-20T21:00:19.658276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.517039ms","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-20T21:00:19.658329Z","caller":"traceutil/trace.go:171","msg":"trace[594420635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"532.59286ms","start":"2026-04-20T21:00:19.125723Z","end":"2026-04-20T21:00:19.658316Z","steps":["trace[594420635] 'agreement among raft nodes before linearized reading' (duration: 532.420826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.658362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.125712Z","time spent":"532.642301ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T21:00:19.658408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.99228ms","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-20T21:00:19.658428Z","caller":"traceutil/trace.go:171","msg":"trace[1802954438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"459.037161ms","start":"2026-04-20T21:00:19.199386Z","end":"2026-04-20T21:00:19.658423Z","steps":["trace[1802954438] 'agreement among raft nodes before linearized reading' (duration: 458.98885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.658442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.199375Z","time spent":"459.064022ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T21:00:19.658501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.42291ms","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-20T21:00:19.658513Z","caller":"traceutil/trace.go:171","msg":"trace[1383447985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1203; }","duration":"459.475041ms","start":"2026-04-20T21:00:19.199033Z","end":"2026-04-20T21:00:19.658508Z","steps":["trace[1383447985] 'agreement among raft nodes before linearized reading' (duration: 459.440031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.658524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.199014Z","time spent":"459.508183ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T21:00:19.658625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.883057ms","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-20T21:00:19.65871Z","caller":"traceutil/trace.go:171","msg":"trace[358912213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"459.012951ms","start":"2026-04-20T21:00:19.199676Z","end":"2026-04-20T21:00:19.658689Z","steps":["trace[358912213] 'agreement among raft nodes before linearized reading' (duration: 458.764145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:19.658775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:19.199659Z","time spent":"459.106303ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T21:00:19.659723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:18.914967Z","time spent":"742.788911ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:23.86939Z","caller":"traceutil/trace.go:171","msg":"trace[1889837335] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"108.248731ms","start":"2026-04-20T21:00:23.761115Z","end":"2026-04-20T21:00:23.869363Z","steps":["trace[1889837335] 'process raft request' (duration: 96.110919ms)","trace[1889837335] 'compare' (duration: 11.967288ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:23.878378Z","caller":"traceutil/trace.go:171","msg":"trace[1653920907] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"116.442318ms","start":"2026-04-20T21:00:23.761915Z","end":"2026-04-20T21:00:23.878357Z","steps":["trace[1653920907] 'process raft request' (duration: 116.406497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:23.878737Z","caller":"traceutil/trace.go:171","msg":"trace[1340807721] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"117.077364ms","start":"2026-04-20T21:00:23.761647Z","end":"2026-04-20T21:00:23.878724Z","steps":["trace[1340807721] 'process raft request' (duration: 116.541781ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:23.878887Z","caller":"traceutil/trace.go:171","msg":"trace[1251904180] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"116.997642ms","start":"2026-04-20T21:00:23.76188Z","end":"2026-04-20T21:00:23.878878Z","steps":["trace[1251904180] 'process raft request' (duration: 116.404478ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:32.221181Z","caller":"traceutil/trace.go:171","msg":"trace[1583633283] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"264.587548ms","start":"2026-04-20T21:00:31.956568Z","end":"2026-04-20T21:00:32.221155Z","steps":["trace[1583633283] 'read index received' (duration: 224.404913ms)","trace[1583633283] 'applied index is now lower than readState.Index' (duration: 40.181715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:32.221526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.809349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:32.221518Z","caller":"traceutil/trace.go:171","msg":"trace[630910275] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"308.895942ms","start":"2026-04-20T21:00:31.912571Z","end":"2026-04-20T21:00:32.221467Z","steps":["trace[630910275] 'process raft request' (duration: 268.42574ms)","trace[630910275] 'compare' (duration: 39.837227ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:32.221575Z","caller":"traceutil/trace.go:171","msg":"trace[450513105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"129.876621ms","start":"2026-04-20T21:00:32.091687Z","end":"2026-04-20T21:00:32.221563Z","steps":["trace[450513105] 'agreement among raft nodes before linearized reading' (duration: 129.789108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:32.221553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.969717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T21:00:32.221596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.181625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T21:00:32.221602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.199515ms","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-20T21:00:32.221644Z","caller":"traceutil/trace.go:171","msg":"trace[332967350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"231.234546ms","start":"2026-04-20T21:00:31.990394Z","end":"2026-04-20T21:00:32.221629Z","steps":["trace[332967350] 'agreement among raft nodes before linearized reading' (duration: 231.087653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:32.221681Z","caller":"traceutil/trace.go:171","msg":"trace[1500619958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"231.301677ms","start":"2026-04-20T21:00:31.990368Z","end":"2026-04-20T21:00:32.221669Z","steps":["trace[1500619958] 'agreement among raft nodes before linearized reading' (duration: 231.088062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:32.221698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:31.912542Z","time spent":"309.059486ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:32.221628Z","caller":"traceutil/trace.go:171","msg":"trace[1891718838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"265.08711ms","start":"2026-04-20T21:00:31.956522Z","end":"2026-04-20T21:00:32.22161Z","steps":["trace[1891718838] 'agreement among raft nodes before linearized reading' (duration: 264.852534ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:32.98009Z","caller":"traceutil/trace.go:171","msg":"trace[1808220569] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"228.43539ms","start":"2026-04-20T21:00:32.75161Z","end":"2026-04-20T21:00:32.980045Z","steps":["trace[1808220569] 'process raft request' (duration: 228.241715ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:33.420624Z","caller":"traceutil/trace.go:171","msg":"trace[371366172] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"266.636407ms","start":"2026-04-20T21:00:33.153957Z","end":"2026-04-20T21:00:33.420594Z","steps":["trace[371366172] 'process raft request' (duration: 179.943774ms)","trace[371366172] 'compare' (duration: 86.512529ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:33.908738Z","caller":"traceutil/trace.go:171","msg":"trace[1812128463] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"669.968141ms","start":"2026-04-20T21:00:33.238748Z","end":"2026-04-20T21:00:33.908716Z","steps":["trace[1812128463] 'process raft request' (duration: 645.207036ms)","trace[1812128463] 'compare' (duration: 24.57997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:33.908838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:33.238717Z","time spent":"670.064864ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:33.937291Z","caller":"traceutil/trace.go:171","msg":"trace[482711943] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"512.841464ms","start":"2026-04-20T21:00:33.424433Z","end":"2026-04-20T21:00:33.937275Z","steps":["trace[482711943] 'process raft request' (duration: 512.65011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:33.937475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:33.424411Z","time spent":"512.952357ms","remote":"127.0.0.1:40720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T21:00:33.937237Z","caller":"traceutil/trace.go:171","msg":"trace[1715926410] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"100.844004ms","start":"2026-04-20T21:00:33.836366Z","end":"2026-04-20T21:00:33.93721Z","steps":["trace[1715926410] 'read index received' (duration: 47.553493ms)","trace[1715926410] 'applied index is now lower than readState.Index' (duration: 53.289351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:33.937796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.395757ms","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-20T21:00:33.937912Z","caller":"traceutil/trace.go:171","msg":"trace[1736915746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1253; }","duration":"101.53636ms","start":"2026-04-20T21:00:33.836328Z","end":"2026-04-20T21:00:33.937864Z","steps":["trace[1736915746] 'agreement among raft nodes before linearized reading' (duration: 101.143781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:34.156402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.197955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T21:00:34.156506Z","caller":"traceutil/trace.go:171","msg":"trace[1146330848] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"154.126344ms","start":"2026-04-20T21:00:34.002364Z","end":"2026-04-20T21:00:34.15649Z","steps":["trace[1146330848] 'read index received' (duration: 29.681834ms)","trace[1146330848] 'applied index is now lower than readState.Index' (duration: 124.44354ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:00:34.156592Z","caller":"traceutil/trace.go:171","msg":"trace[1229239713] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"214.958336ms","start":"2026-04-20T21:00:33.941591Z","end":"2026-04-20T21:00:34.156549Z","steps":["trace[1229239713] 'process raft request' (duration: 90.529816ms)","trace[1229239713] 'compare' (duration: 124.079912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:34.15668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.314018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-20T21:00:34.156708Z","caller":"traceutil/trace.go:171","msg":"trace[873021622] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1254; }","duration":"154.37993ms","start":"2026-04-20T21:00:34.002319Z","end":"2026-04-20T21:00:34.156699Z","steps":["trace[873021622] 'agreement among raft nodes before linearized reading' (duration: 154.256257ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:34.342902Z","caller":"traceutil/trace.go:171","msg":"trace[565223677] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"107.976644ms","start":"2026-04-20T21:00:34.234847Z","end":"2026-04-20T21:00:34.342823Z","steps":["trace[565223677] 'process raft request' (duration: 107.858812ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:34.361208Z","caller":"traceutil/trace.go:171","msg":"trace[607363457] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"124.943973ms","start":"2026-04-20T21:00:34.236241Z","end":"2026-04-20T21:00:34.361185Z","steps":["trace[607363457] 'process raft request' (duration: 124.686517ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:35.844902Z","caller":"traceutil/trace.go:171","msg":"trace[1915291874] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"321.889356ms","start":"2026-04-20T21:00:35.522977Z","end":"2026-04-20T21:00:35.844867Z","steps":["trace[1915291874] 'process raft request' (duration: 320.84803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:35.846902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:35.522968Z","time spent":"323.841192ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:36.151718Z","caller":"traceutil/trace.go:171","msg":"trace[1184498658] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"301.181597ms","start":"2026-04-20T21:00:35.850515Z","end":"2026-04-20T21:00:36.151696Z","steps":["trace[1184498658] 'process raft request' (duration: 250.655473ms)","trace[1184498658] 'compare' (duration: 50.315729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:36.151852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:35.850501Z","time spent":"301.27958ms","remote":"127.0.0.1:40808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T21:00:36.152069Z","caller":"traceutil/trace.go:171","msg":"trace[788671161] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"295.151913ms","start":"2026-04-20T21:00:35.856882Z","end":"2026-04-20T21:00:36.152033Z","steps":["trace[788671161] 'process raft request' (duration: 294.763704ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:36.152227Z","caller":"traceutil/trace.go:171","msg":"trace[1386665326] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"233.573273ms","start":"2026-04-20T21:00:35.918645Z","end":"2026-04-20T21:00:36.152218Z","steps":["trace[1386665326] 'process raft request' (duration: 233.258186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:36.707437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.865215ms","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-20T21:00:36.707513Z","caller":"traceutil/trace.go:171","msg":"trace[1143209191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1269; }","duration":"296.976248ms","start":"2026-04-20T21:00:36.410519Z","end":"2026-04-20T21:00:36.707495Z","steps":["trace[1143209191] 'range keys from in-memory index tree' (duration: 296.713041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:36.70783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.619403ms","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-20T21:00:36.707875Z","caller":"traceutil/trace.go:171","msg":"trace[1856775581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"359.697195ms","start":"2026-04-20T21:00:36.348161Z","end":"2026-04-20T21:00:36.707859Z","steps":["trace[1856775581] 'range keys from in-memory index tree' (duration: 359.47322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:36.707904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:36.348139Z","time spent":"359.756837ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T21:00:36.708252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.503673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:00:36.708289Z","caller":"traceutil/trace.go:171","msg":"trace[1776579441] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1269; }","duration":"330.548244ms","start":"2026-04-20T21:00:36.377731Z","end":"2026-04-20T21:00:36.70828Z","steps":["trace[1776579441] 'count revisions from in-memory index tree' (duration: 330.37888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:36.708319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:36.37767Z","time spent":"330.639917ms","remote":"127.0.0.1:60520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-20T21:00:36.708503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.725465ms","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-20T21:00:36.708563Z","caller":"traceutil/trace.go:171","msg":"trace[688844337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1269; }","duration":"329.811326ms","start":"2026-04-20T21:00:36.378736Z","end":"2026-04-20T21:00:36.708547Z","steps":["trace[688844337] 'range keys from in-memory index tree' (duration: 329.622972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:00:36.708618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:36.378718Z","time spent":"329.884939ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T21:00:36.709084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.77167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T21:00:36.709232Z","caller":"traceutil/trace.go:171","msg":"trace[1096417895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1269; }","duration":"298.951625ms","start":"2026-04-20T21:00:36.410257Z","end":"2026-04-20T21:00:36.709208Z","steps":["trace[1096417895] 'range keys from in-memory index tree' (duration: 298.527914ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:48.713744Z","caller":"traceutil/trace.go:171","msg":"trace[1428926297] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"424.505681ms","start":"2026-04-20T21:00:48.289029Z","end":"2026-04-20T21:00:48.713535Z","steps":["trace[1428926297] 'process raft request' (duration: 334.305703ms)","trace[1428926297] 'compare' (duration: 89.941552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T21:00:48.71398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T21:00:48.289009Z","time spent":"424.822798ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T21:00:48.714227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.768912ms","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-20T21:00:48.714324Z","caller":"traceutil/trace.go:171","msg":"trace[5302776] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1361; }","duration":"172.914116ms","start":"2026-04-20T21:00:48.541389Z","end":"2026-04-20T21:00:48.714303Z","steps":["trace[5302776] 'agreement among raft nodes before linearized reading' (duration: 172.392032ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:00:48.713742Z","caller":"traceutil/trace.go:171","msg":"trace[1048518292] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"171.946921ms","start":"2026-04-20T21:00:48.541442Z","end":"2026-04-20T21:00:48.713389Z","steps":["trace[1048518292] 'read index received' (duration: 81.802935ms)","trace[1048518292] 'applied index is now lower than readState.Index' (duration: 90.142726ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:01:04.97848Z","caller":"traceutil/trace.go:171","msg":"trace[1371352560] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"125.994528ms","start":"2026-04-20T21:01:04.852464Z","end":"2026-04-20T21:01:04.978459Z","steps":["trace[1371352560] 'process raft request' (duration: 42.078791ms)","trace[1371352560] 'compare' (duration: 83.759003ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:01:04.978647Z","caller":"traceutil/trace.go:171","msg":"trace[204718534] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1499; }","duration":"116.179631ms","start":"2026-04-20T21:01:04.862456Z","end":"2026-04-20T21:01:04.978635Z","steps":["trace[204718534] 'read index received' (duration: 32.123521ms)","trace[204718534] 'applied index is now lower than readState.Index' (duration: 84.0552ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:01:04.978774Z","caller":"traceutil/trace.go:171","msg":"trace[257841582] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"123.098198ms","start":"2026-04-20T21:01:04.855636Z","end":"2026-04-20T21:01:04.978734Z","steps":["trace[257841582] 'process raft request' (duration: 122.78477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:01:04.978819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.356747ms","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-20T21:01:04.978875Z","caller":"traceutil/trace.go:171","msg":"trace[1964627127] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1444; }","duration":"116.438448ms","start":"2026-04-20T21:01:04.862417Z","end":"2026-04-20T21:01:04.978855Z","steps":["trace[1964627127] 'agreement among raft nodes before linearized reading' (duration: 116.297884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:01:04.978818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.394012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T21:01:04.978982Z","caller":"traceutil/trace.go:171","msg":"trace[828025052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1444; }","duration":"102.582835ms","start":"2026-04-20T21:01:04.876366Z","end":"2026-04-20T21:01:04.978948Z","steps":["trace[828025052] 'agreement among raft nodes before linearized reading' (duration: 102.36142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:01:21.003971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.803166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:01:21.004079Z","caller":"traceutil/trace.go:171","msg":"trace[1803671900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1526; }","duration":"104.97247ms","start":"2026-04-20T21:01:20.89908Z","end":"2026-04-20T21:01:21.004052Z","steps":["trace[1803671900] 'count revisions from in-memory index tree' (duration: 104.667813ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:01:37.46091Z","caller":"traceutil/trace.go:171","msg":"trace[928667529] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"121.246434ms","start":"2026-04-20T21:01:37.339639Z","end":"2026-04-20T21:01:37.460885Z","steps":["trace[928667529] 'process raft request' (duration: 120.610318ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:01:37.486182Z","caller":"traceutil/trace.go:171","msg":"trace[1511641608] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"145.032177ms","start":"2026-04-20T21:01:37.341099Z","end":"2026-04-20T21:01:37.486131Z","steps":["trace[1511641608] 'process raft request' (duration: 144.774269ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:02:01.519405Z","caller":"traceutil/trace.go:171","msg":"trace[1596960256] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"109.178172ms","start":"2026-04-20T21:02:01.410193Z","end":"2026-04-20T21:02:01.519371Z","steps":["trace[1596960256] 'process raft request' (duration: 55.123778ms)","trace[1596960256] 'compare' (duration: 53.840479ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:03:33.108236Z","caller":"traceutil/trace.go:171","msg":"trace[934102275] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"122.043975ms","start":"2026-04-20T21:03:32.986169Z","end":"2026-04-20T21:03:33.108213Z","steps":["trace[934102275] 'process raft request' (duration: 121.85223ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:03:33.174445Z","caller":"traceutil/trace.go:171","msg":"trace[951685307] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2283; }","duration":"138.575167ms","start":"2026-04-20T21:03:33.035845Z","end":"2026-04-20T21:03:33.17442Z","steps":["trace[951685307] 'read index received' (duration: 72.126858ms)","trace[951685307] 'applied index is now lower than readState.Index' (duration: 66.447239ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:03:33.174618Z","caller":"traceutil/trace.go:171","msg":"trace[485869692] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"188.076364ms","start":"2026-04-20T21:03:32.986502Z","end":"2026-04-20T21:03:33.174579Z","steps":["trace[485869692] 'process raft request' (duration: 187.761196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:03:33.174676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.821533ms","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-20T21:03:33.174718Z","caller":"traceutil/trace.go:171","msg":"trace[1244406750] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2198; }","duration":"138.912005ms","start":"2026-04-20T21:03:33.035792Z","end":"2026-04-20T21:03:33.174704Z","steps":["trace[1244406750] 'agreement among raft nodes before linearized reading' (duration: 138.756631ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:03:34.139682Z","caller":"traceutil/trace.go:171","msg":"trace[453674832] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"155.91092ms","start":"2026-04-20T21:03:33.983744Z","end":"2026-04-20T21:03:34.139655Z","steps":["trace[453674832] 'process raft request' (duration: 155.685545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:03:34.231211Z","caller":"traceutil/trace.go:171","msg":"trace[814777466] linearizableReadLoop","detail":"{readStateIndex:2290; appliedIndex:2288; }","duration":"172.867632ms","start":"2026-04-20T21:03:34.058308Z","end":"2026-04-20T21:03:34.231175Z","steps":["trace[814777466] 'read index received' (duration: 81.234639ms)","trace[814777466] 'applied index is now lower than readState.Index' (duration: 91.631773ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:03:34.231513Z","caller":"traceutil/trace.go:171","msg":"trace[1692256700] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"246.445036ms","start":"2026-04-20T21:03:33.985042Z","end":"2026-04-20T21:03:34.231487Z","steps":["trace[1692256700] 'process raft request' (duration: 245.305337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:03:34.231626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.488757ms","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":"warn","ts":"2026-04-20T21:03:34.231619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.120154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:03:34.231685Z","caller":"traceutil/trace.go:171","msg":"trace[577330732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2203; }","duration":"157.583009ms","start":"2026-04-20T21:03:34.074087Z","end":"2026-04-20T21:03:34.23167Z","steps":["trace[577330732] 'agreement among raft nodes before linearized reading' (duration: 157.348413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:03:34.231789Z","caller":"traceutil/trace.go:171","msg":"trace[913293298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2203; }","duration":"140.256587ms","start":"2026-04-20T21:03:34.091431Z","end":"2026-04-20T21:03:34.231688Z","steps":["trace[913293298] 'agreement among raft nodes before linearized reading' (duration: 140.086693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:03:34.231824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.487738ms","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-20T21:03:34.231925Z","caller":"traceutil/trace.go:171","msg":"trace[985514759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2203; }","duration":"173.597231ms","start":"2026-04-20T21:03:34.058301Z","end":"2026-04-20T21:03:34.231899Z","steps":["trace[985514759] 'agreement among raft nodes before linearized reading' (duration: 173.057368ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:04:01.650772Z","caller":"traceutil/trace.go:171","msg":"trace[235490619] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"171.609113ms","start":"2026-04-20T21:04:01.479133Z","end":"2026-04-20T21:04:01.650742Z","steps":["trace[235490619] 'process raft request' (duration: 171.402968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:04:01.825075Z","caller":"traceutil/trace.go:171","msg":"trace[1136039905] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"152.957087ms","start":"2026-04-20T21:04:01.672069Z","end":"2026-04-20T21:04:01.825026Z","steps":["trace[1136039905] 'process raft request' (duration: 69.124514ms)","trace[1136039905] 'compare' (duration: 83.480095ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:04:01.825689Z","caller":"traceutil/trace.go:171","msg":"trace[162580887] linearizableReadLoop","detail":"{readStateIndex:2465; appliedIndex:2463; }","duration":"147.598366ms","start":"2026-04-20T21:04:01.678071Z","end":"2026-04-20T21:04:01.82567Z","steps":["trace[162580887] 'read index received' (duration: 63.109838ms)","trace[162580887] 'applied index is now lower than readState.Index' (duration: 84.486538ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T21:04:01.825803Z","caller":"traceutil/trace.go:171","msg":"trace[230214997] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"152.360462ms","start":"2026-04-20T21:04:01.67343Z","end":"2026-04-20T21:04:01.82579Z","steps":["trace[230214997] 'process raft request' (duration: 151.530692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T21:04:01.825951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.835543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T21:04:01.826207Z","caller":"traceutil/trace.go:171","msg":"trace[1791425435] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2372; }","duration":"148.15699ms","start":"2026-04-20T21:04:01.678035Z","end":"2026-04-20T21:04:01.826192Z","steps":["trace[1791425435] 'agreement among raft nodes before linearized reading' (duration: 147.823932ms)"],"step_count":1}