{"level":"warn","ts":"2026-07-30T19:20:56.825704Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T19:20:56.825797Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-07-30T19:20:56.825872Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T19:20:56.825883Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-30T19:20:56.825903Z","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-07-30T19:20:56.826563Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-07-30T19:20:56.826658Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-07-30T19:20:56.833514Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.512982ms"} {"level":"info","ts":"2026-07-30T19:20:56.842422Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-07-30T19:20:56.842504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T19:20:56.842528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-07-30T19:20:56.842539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T19:20:56.84255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-07-30T19:20:56.842585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-07-30T19:20:56.857934Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T19:20:56.85995Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T19:20:56.861395Z","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-07-30T19:20:56.863649Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T19:20:56.863882Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T19:20:56.863933Z","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-07-30T19:20:56.864051Z","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-07-30T19:20:56.864065Z","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-07-30T19:20:56.865805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-07-30T19:20:56.866213Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-30T19:20:56.868066Z","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-07-30T19:20:56.868098Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-30T19:20:56.868137Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-30T19:20:56.868378Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T19:20:56.868439Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T19:20:56.943374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T19:20:56.943429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T19:20:56.94346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-07-30T19:20:56.943478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-07-30T19:20:56.943501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-30T19:20:56.94352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-07-30T19:20:56.943533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-30T19:20:56.9447Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T19:20:56.944858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T19:20:56.944921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T19:20:56.945061Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:20:56.945299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T19:20:56.945404Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T19:20:56.946219Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:20:56.946346Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:20:56.94638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T19:20:56.947747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T19:20:56.94832Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-07-30T19:21:00.292076Z","caller":"traceutil/trace.go:171","msg":"trace[1164634514] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"108.779249ms","start":"2026-07-30T19:21:00.183264Z","end":"2026-07-30T19:21:00.292043Z","steps":["trace[1164634514] 'process raft request' (duration: 108.731208ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:00.29211Z","caller":"traceutil/trace.go:171","msg":"trace[1596443257] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"214.341192ms","start":"2026-07-30T19:21:00.077736Z","end":"2026-07-30T19:21:00.292077Z","steps":["trace[1596443257] 'process raft request' (duration: 203.540995ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:12.998239Z","caller":"traceutil/trace.go:171","msg":"trace[915133100] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"277.93064ms","start":"2026-07-30T19:21:12.720281Z","end":"2026-07-30T19:21:12.998212Z","steps":["trace[915133100] 'read index received' (duration: 277.791657ms)","trace[915133100] 'applied index is now lower than readState.Index' (duration: 137.513µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:12.99845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.119306ms","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-07-30T19:21:12.998518Z","caller":"traceutil/trace.go:171","msg":"trace[1420665498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:365; }","duration":"278.28542ms","start":"2026-07-30T19:21:12.720216Z","end":"2026-07-30T19:21:12.998501Z","steps":["trace[1420665498] 'agreement among raft nodes before linearized reading' (duration: 278.119185ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:12.998594Z","caller":"traceutil/trace.go:171","msg":"trace[443466068] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"285.531583ms","start":"2026-07-30T19:21:12.712782Z","end":"2026-07-30T19:21:12.998313Z","steps":["trace[443466068] 'process raft request' (duration: 285.238045ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:24.017529Z","caller":"traceutil/trace.go:171","msg":"trace[691094026] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"284.447864ms","start":"2026-07-30T19:21:23.733053Z","end":"2026-07-30T19:21:24.017501Z","steps":["trace[691094026] 'read index received' (duration: 284.196418ms)","trace[691094026] 'applied index is now lower than readState.Index' (duration: 250.576µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:24.01765Z","caller":"traceutil/trace.go:171","msg":"trace[1889830469] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"486.950991ms","start":"2026-07-30T19:21:23.530659Z","end":"2026-07-30T19:21:24.01761Z","steps":["trace[1889830469] 'process raft request' (duration: 486.674884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:24.017715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.634479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:21:24.017769Z","caller":"traceutil/trace.go:171","msg":"trace[6391497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"284.743961ms","start":"2026-07-30T19:21:23.733011Z","end":"2026-07-30T19:21:24.017755Z","steps":["trace[6391497] 'agreement among raft nodes before linearized reading' (duration: 284.640669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:24.018218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:23.530632Z","time spent":"487.101786ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:41.137001Z","caller":"traceutil/trace.go:171","msg":"trace[1757132607] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"113.206716ms","start":"2026-07-30T19:21:41.023665Z","end":"2026-07-30T19:21:41.136872Z","steps":["trace[1757132607] 'process raft request' (duration: 108.47494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:42.233441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063955266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:21:42.381944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004839574s","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-07-30T19:21:42.382026Z","caller":"traceutil/trace.go:171","msg":"trace[1693538120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"1.004976418s","start":"2026-07-30T19:21:41.377031Z","end":"2026-07-30T19:21:42.382008Z","steps":["trace[1693538120] 'range keys from in-memory index tree' (duration: 1.00466665s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:42.382056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:41.377002Z","time spent":"1.00504594s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T19:21:42.382848Z","caller":"traceutil/trace.go:171","msg":"trace[190337446] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"650.059063ms","start":"2026-07-30T19:21:41.732772Z","end":"2026-07-30T19:21:42.382832Z","steps":["trace[190337446] 'read index received' (duration: 554.838435ms)","trace[190337446] 'applied index is now lower than readState.Index' (duration: 95.220038ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:42.383164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.976262ms","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-07-30T19:21:42.383204Z","caller":"traceutil/trace.go:171","msg":"trace[724740317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"269.039504ms","start":"2026-07-30T19:21:42.114153Z","end":"2026-07-30T19:21:42.383193Z","steps":["trace[724740317] 'agreement among raft nodes before linearized reading' (duration: 268.934292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:42.383157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.369531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:21:42.383262Z","caller":"traceutil/trace.go:171","msg":"trace[1147971959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"650.473343ms","start":"2026-07-30T19:21:41.73276Z","end":"2026-07-30T19:21:42.383233Z","steps":["trace[1147971959] 'agreement among raft nodes before linearized reading' (duration: 650.299619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:42.383311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:41.732713Z","time spent":"650.590596ms","remote":"127.0.0.1:56420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:21:42.383422Z","caller":"traceutil/trace.go:171","msg":"trace[201509980] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"702.736421ms","start":"2026-07-30T19:21:41.680656Z","end":"2026-07-30T19:21:42.383392Z","steps":["trace[201509980] 'process raft request' (duration: 607.199715ms)","trace[201509980] 'compare' (duration: 94.138789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:42.383563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:41.680638Z","time spent":"702.838724ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:21:42.675542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.933948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:21:42.676475Z","caller":"traceutil/trace.go:171","msg":"trace[2067074875] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"289.302563ms","start":"2026-07-30T19:21:42.38713Z","end":"2026-07-30T19:21:42.676433Z","steps":["trace[2067074875] 'process raft request' (duration: 102.091932ms)","trace[2067074875] 'compare' (duration: 185.779523ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:42.676488Z","caller":"traceutil/trace.go:171","msg":"trace[373639776] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"237.723683ms","start":"2026-07-30T19:21:42.438717Z","end":"2026-07-30T19:21:42.67644Z","steps":["trace[373639776] 'process raft request' (duration: 237.587999ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:42.676768Z","caller":"traceutil/trace.go:171","msg":"trace[48329190] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"288.637575ms","start":"2026-07-30T19:21:42.388095Z","end":"2026-07-30T19:21:42.676733Z","steps":["trace[48329190] 'process raft request' (duration: 288.123742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:43.678039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063955275,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:21:44.065977Z","caller":"traceutil/trace.go:171","msg":"trace[1254789629] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"888.65094ms","start":"2026-07-30T19:21:43.177297Z","end":"2026-07-30T19:21:44.065948Z","steps":["trace[1254789629] 'read index received' (duration: 886.463912ms)","trace[1254789629] 'applied index is now lower than readState.Index' (duration: 2.185878ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:21:44.066267Z","caller":"traceutil/trace.go:171","msg":"trace[740258519] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"613.868912ms","start":"2026-07-30T19:21:43.452379Z","end":"2026-07-30T19:21:44.066248Z","steps":["trace[740258519] 'process raft request' (duration: 613.498193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:44.066401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.0974ms","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-07-30T19:21:44.066462Z","caller":"traceutil/trace.go:171","msg":"trace[1230831356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"889.201823ms","start":"2026-07-30T19:21:43.177249Z","end":"2026-07-30T19:21:44.06645Z","steps":["trace[1230831356] 'agreement among raft nodes before linearized reading' (duration: 888.989648ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:44.066062Z","caller":"traceutil/trace.go:171","msg":"trace[647282927] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"980.021625ms","start":"2026-07-30T19:21:43.086013Z","end":"2026-07-30T19:21:44.066035Z","steps":["trace[647282927] 'process raft request' (duration: 977.971361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:44.066494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:43.177226Z","time spent":"889.261685ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T19:21:44.066404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.364866ms","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-07-30T19:21:44.066478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:43.452355Z","time spent":"613.995065ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:44.066555Z","caller":"traceutil/trace.go:171","msg":"trace[1220511752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"808.577463ms","start":"2026-07-30T19:21:43.257963Z","end":"2026-07-30T19:21:44.06654Z","steps":["trace[1220511752] 'agreement among raft nodes before linearized reading' (duration: 808.272224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:44.066582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:43.25794Z","time spent":"808.636083ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T19:21:44.066786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:43.085964Z","time spent":"980.528828ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:21:49.10347Z","caller":"traceutil/trace.go:171","msg":"trace[86484142] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"176.079575ms","start":"2026-07-30T19:21:48.927367Z","end":"2026-07-30T19:21:49.103447Z","steps":["trace[86484142] 'process raft request' (duration: 175.584782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:57.005731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.497093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-hpx27\" ","response":"range_response_count:1 size:5050"} {"level":"info","ts":"2026-07-30T19:21:57.005849Z","caller":"traceutil/trace.go:171","msg":"trace[2145660974] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-hpx27; range_end:; response_count:1; response_revision:744; }","duration":"153.669037ms","start":"2026-07-30T19:21:56.85216Z","end":"2026-07-30T19:21:57.005829Z","steps":["trace[2145660974] 'range keys from in-memory index tree' (duration: 152.481385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:38.190708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.553216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:38.190799Z","caller":"traceutil/trace.go:171","msg":"trace[1064364569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"460.67923ms","start":"2026-07-30T19:22:37.730107Z","end":"2026-07-30T19:22:38.190786Z","steps":["trace[1064364569] 'range keys from in-memory index tree' (duration: 460.468883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:38.190824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:37.73009Z","time spent":"460.72815ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:22:38.190699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.159997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:22:38.190962Z","caller":"traceutil/trace.go:171","msg":"trace[1160054354] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1041; }","duration":"494.444606ms","start":"2026-07-30T19:22:37.696481Z","end":"2026-07-30T19:22:38.190926Z","steps":["trace[1160054354] 'range keys from in-memory index tree' (duration: 494.063875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:22:38.191087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:22:37.696464Z","time spent":"494.595549ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-07-30T19:22:38.503827Z","caller":"traceutil/trace.go:171","msg":"trace[1943469760] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"159.972894ms","start":"2026-07-30T19:22:38.343833Z","end":"2026-07-30T19:22:38.503806Z","steps":["trace[1943469760] 'process raft request' (duration: 156.730208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:03.234088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063956734,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:23:03.570443Z","caller":"traceutil/trace.go:171","msg":"trace[619450955] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"836.938498ms","start":"2026-07-30T19:23:02.73348Z","end":"2026-07-30T19:23:03.570418Z","steps":["trace[619450955] 'read index received' (duration: 836.759394ms)","trace[619450955] 'applied index is now lower than readState.Index' (duration: 177.954µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:03.570698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.179704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:23:03.570846Z","caller":"traceutil/trace.go:171","msg":"trace[903536629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"837.39428ms","start":"2026-07-30T19:23:02.733428Z","end":"2026-07-30T19:23:03.570823Z","steps":["trace[903536629] 'agreement among raft nodes before linearized reading' (duration: 837.095022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:03.570906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:02.733407Z","time spent":"837.489583ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:23:03.57117Z","caller":"traceutil/trace.go:171","msg":"trace[2024134720] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"876.474348ms","start":"2026-07-30T19:23:02.694064Z","end":"2026-07-30T19:23:03.570539Z","steps":["trace[2024134720] 'process raft request' (duration: 876.193931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:03.571384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:02.694029Z","time spent":"877.254749ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:03.579053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.774824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T19:23:03.579114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.308618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T19:23:03.579141Z","caller":"traceutil/trace.go:171","msg":"trace[1557965267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"526.920118ms","start":"2026-07-30T19:23:03.0522Z","end":"2026-07-30T19:23:03.57912Z","steps":["trace[1557965267] 'agreement among raft nodes before linearized reading' (duration: 526.646841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:03.579209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:03.052177Z","time spent":"527.017991ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T19:23:03.579189Z","caller":"traceutil/trace.go:171","msg":"trace[1971180182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"524.399362ms","start":"2026-07-30T19:23:03.054771Z","end":"2026-07-30T19:23:03.57917Z","steps":["trace[1971180182] 'agreement among raft nodes before linearized reading' (duration: 524.136454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:03.579336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:03.05471Z","time spent":"524.613108ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T19:23:04.040919Z","caller":"traceutil/trace.go:171","msg":"trace[1890021400] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"457.478115ms","start":"2026-07-30T19:23:03.583408Z","end":"2026-07-30T19:23:04.040886Z","steps":["trace[1890021400] 'process raft request' (duration: 390.933048ms)","trace[1890021400] 'compare' (duration: 66.325621ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:04.041041Z","caller":"traceutil/trace.go:171","msg":"trace[1004875938] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1376; }","duration":"306.726623ms","start":"2026-07-30T19:23:03.734295Z","end":"2026-07-30T19:23:04.041021Z","steps":["trace[1004875938] 'read index received' (duration: 240.037543ms)","trace[1004875938] 'applied index is now lower than readState.Index' (duration: 66.68766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:04.041102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:03.583374Z","time spent":"457.631689ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:04.041167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.864677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:23:04.041228Z","caller":"traceutil/trace.go:171","msg":"trace[1484926484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1333; }","duration":"306.95524ms","start":"2026-07-30T19:23:03.734259Z","end":"2026-07-30T19:23:04.041214Z","steps":["trace[1484926484] 'agreement among raft nodes before linearized reading' (duration: 306.864476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:04.041279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:03.734239Z","time spent":"307.025671ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:23:04.041154Z","caller":"traceutil/trace.go:171","msg":"trace[1698674878] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"457.012712ms","start":"2026-07-30T19:23:03.584115Z","end":"2026-07-30T19:23:04.041128Z","steps":["trace[1698674878] 'process raft request' (duration: 456.715414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:04.041392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.885669ms","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-07-30T19:23:04.041496Z","caller":"traceutil/trace.go:171","msg":"trace[2092741849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"234.046223ms","start":"2026-07-30T19:23:03.807431Z","end":"2026-07-30T19:23:04.041478Z","steps":["trace[2092741849] 'agreement among raft nodes before linearized reading' (duration: 233.763216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:04.041433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:03.584092Z","time spent":"457.248719ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:05.072366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.59179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:23:05.072505Z","caller":"traceutil/trace.go:171","msg":"trace[1583119951] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"467.715297ms","start":"2026-07-30T19:23:04.604757Z","end":"2026-07-30T19:23:05.072472Z","steps":["trace[1583119951] 'read index received' (duration: 306.005284ms)","trace[1583119951] 'applied index is now lower than readState.Index' (duration: 161.707883ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:05.072583Z","caller":"traceutil/trace.go:171","msg":"trace[614390458] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"551.754217ms","start":"2026-07-30T19:23:04.520817Z","end":"2026-07-30T19:23:05.072571Z","steps":["trace[614390458] 'process raft request' (duration: 389.836758ms)","trace[614390458] 'compare' (duration: 161.381945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:05.07264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:04.520806Z","time spent":"551.803378ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:05.072786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.003604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:23:05.072892Z","caller":"traceutil/trace.go:171","msg":"trace[796532228] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1337; }","duration":"468.164049ms","start":"2026-07-30T19:23:04.604707Z","end":"2026-07-30T19:23:05.072871Z","steps":["trace[796532228] 'agreement among raft nodes before linearized reading' (duration: 467.944093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:05.072983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:04.604689Z","time spent":"468.281161ms","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:23:05.573577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063956753,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:23:05.966425Z","caller":"traceutil/trace.go:171","msg":"trace[653270109] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"893.863079ms","start":"2026-07-30T19:23:05.072526Z","end":"2026-07-30T19:23:05.966389Z","steps":["trace[653270109] 'read index received' (duration: 847.378395ms)","trace[653270109] 'applied index is now lower than readState.Index' (duration: 46.483084ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:05.966485Z","caller":"traceutil/trace.go:171","msg":"trace[203408476] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"997.863961ms","start":"2026-07-30T19:23:04.968587Z","end":"2026-07-30T19:23:05.966451Z","steps":["trace[203408476] 'process raft request' (duration: 951.293905ms)","trace[203408476] 'compare' (duration: 46.047322ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:05.966646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233350273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:23:05.966686Z","caller":"traceutil/trace.go:171","msg":"trace[1880323042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"1.233445305s","start":"2026-07-30T19:23:04.733228Z","end":"2026-07-30T19:23:05.966673Z","steps":["trace[1880323042] 'agreement among raft nodes before linearized reading' (duration: 1.233335932s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:05.966686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:04.968568Z","time spent":"998.019835ms","remote":"127.0.0.1:56558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7196,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:05.966717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:04.733203Z","time spent":"1.233505886s","remote":"127.0.0.1:56420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:23:05.966751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013760433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:23:05.966758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040715218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:23:05.966832Z","caller":"traceutil/trace.go:171","msg":"trace[114943021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"1.013909816s","start":"2026-07-30T19:23:04.952898Z","end":"2026-07-30T19:23:05.966808Z","steps":["trace[114943021] 'agreement among raft nodes before linearized reading' (duration: 1.013743252s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:05.966879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:04.952864Z","time spent":"1.014007149s","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:23:05.966892Z","caller":"traceutil/trace.go:171","msg":"trace[1488816559] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1338; }","duration":"1.040930014s","start":"2026-07-30T19:23:04.925929Z","end":"2026-07-30T19:23:05.966859Z","steps":["trace[1488816559] 'agreement among raft nodes before linearized reading' (duration: 1.040630696s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:05.966969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:04.925911Z","time spent":"1.041043307s","remote":"127.0.0.1:56856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:23:05.986644Z","caller":"traceutil/trace.go:171","msg":"trace[1150147228] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"401.120589ms","start":"2026-07-30T19:23:05.585495Z","end":"2026-07-30T19:23:05.986615Z","steps":["trace[1150147228] 'process raft request' (duration: 400.920144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:05.986803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:05.585466Z","time spent":"401.245142ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:05.986939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.791505ms","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-07-30T19:23:05.987129Z","caller":"traceutil/trace.go:171","msg":"trace[842824566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"410.929119ms","start":"2026-07-30T19:23:05.576073Z","end":"2026-07-30T19:23:05.987002Z","steps":["trace[842824566] 'agreement among raft nodes before linearized reading' (duration: 410.560328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:05.987202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:05.576048Z","time spent":"411.145535ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T19:23:08.511358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063956781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:23:09.012115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063956781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:23:09.059286Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06639127s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T19:23:09.059691Z","caller":"traceutil/trace.go:171","msg":"trace[832398984] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"1.066921494s","start":"2026-07-30T19:23:07.992746Z","end":"2026-07-30T19:23:09.059667Z","steps":["trace[832398984] 'process raft request' (duration: 1.06675317s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.059849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:07.992716Z","time spent":"1.067035327s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:09.060355Z","caller":"traceutil/trace.go:171","msg":"trace[87087844] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1399; }","duration":"1.049979134s","start":"2026-07-30T19:23:08.010355Z","end":"2026-07-30T19:23:09.060335Z","steps":["trace[87087844] 'read index received' (duration: 1.049162962s)","trace[87087844] 'applied index is now lower than readState.Index' (duration: 814.862µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:09.060465Z","caller":"traceutil/trace.go:171","msg":"trace[1731252259] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"515.401212ms","start":"2026-07-30T19:23:08.54505Z","end":"2026-07-30T19:23:09.060451Z","steps":["trace[1731252259] 'process raft request' (duration: 515.175636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.06055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:08.545034Z","time spent":"515.464934ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:09.060662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050290562s","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-07-30T19:23:09.060663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.044474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T19:23:09.06075Z","caller":"traceutil/trace.go:171","msg":"trace[1116330956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1354; }","duration":"1.050388425s","start":"2026-07-30T19:23:08.010343Z","end":"2026-07-30T19:23:09.060731Z","steps":["trace[1116330956] 'agreement among raft nodes before linearized reading' (duration: 1.050164119s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.060798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:08.010296Z","time spent":"1.050492567s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T19:23:09.06082Z","caller":"traceutil/trace.go:171","msg":"trace[477215344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1354; }","duration":"926.25269ms","start":"2026-07-30T19:23:08.134545Z","end":"2026-07-30T19:23:09.060797Z","steps":["trace[477215344] 'agreement among raft nodes before linearized reading' (duration: 925.972162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.060802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.067925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T19:23:09.060732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.674017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-30T19:23:09.060875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:08.134523Z","time spent":"926.340063ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T19:23:09.060884Z","caller":"traceutil/trace.go:171","msg":"trace[122193817] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1354; }","duration":"772.166609ms","start":"2026-07-30T19:23:08.288701Z","end":"2026-07-30T19:23:09.060868Z","steps":["trace[122193817] 'agreement among raft nodes before linearized reading' (duration: 772.065296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.060916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.864443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T19:23:09.060936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:08.28868Z","time spent":"772.241521ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:23:09.06093Z","caller":"traceutil/trace.go:171","msg":"trace[85386797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1354; }","duration":"901.848493ms","start":"2026-07-30T19:23:08.15904Z","end":"2026-07-30T19:23:09.060889Z","steps":["trace[85386797] 'agreement among raft nodes before linearized reading' (duration: 901.558965ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:09.060975Z","caller":"traceutil/trace.go:171","msg":"trace[662588065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"901.958006ms","start":"2026-07-30T19:23:08.159003Z","end":"2026-07-30T19:23:09.060961Z","steps":["trace[662588065] 'agreement among raft nodes before linearized reading' (duration: 901.820561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.061026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:08.158983Z","time spent":"902.031457ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T19:23:09.061064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:08.158984Z","time spent":"902.012766ms","remote":"127.0.0.1:56620","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-07-30T19:23:09.675179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.690275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:23:09.675296Z","caller":"traceutil/trace.go:171","msg":"trace[1735524210] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"609.666206ms","start":"2026-07-30T19:23:09.06561Z","end":"2026-07-30T19:23:09.675277Z","steps":["trace[1735524210] 'process raft request' (duration: 74.787536ms)","trace[1735524210] 'compare' (duration: 534.396956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:09.675358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:09.065585Z","time spent":"609.741567ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:09.67639Z","caller":"traceutil/trace.go:171","msg":"trace[1269674379] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1401; }","duration":"537.397637ms","start":"2026-07-30T19:23:09.138977Z","end":"2026-07-30T19:23:09.676374Z","steps":["trace[1269674379] 'read index received' (duration: 1.399368ms)","trace[1269674379] 'applied index is now lower than readState.Index' (duration: 535.997319ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:09.676478Z","caller":"traceutil/trace.go:171","msg":"trace[421729432] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"610.313253ms","start":"2026-07-30T19:23:09.066157Z","end":"2026-07-30T19:23:09.67647Z","steps":["trace[421729432] 'process raft request' (duration: 610.169799ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:09.676471Z","caller":"traceutil/trace.go:171","msg":"trace[621322567] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"610.262982ms","start":"2026-07-30T19:23:09.066173Z","end":"2026-07-30T19:23:09.676436Z","steps":["trace[621322567] 'process raft request' (duration: 610.053876ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:09.67656Z","caller":"traceutil/trace.go:171","msg":"trace[1000870015] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"609.85376ms","start":"2026-07-30T19:23:09.06668Z","end":"2026-07-30T19:23:09.676534Z","steps":["trace[1000870015] 'process raft request' (duration: 609.670326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.676592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:09.066133Z","time spent":"610.363224ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:09.676635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:09.066656Z","time spent":"609.939882ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:09.676631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:09.066159Z","time spent":"610.380764ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:09.676709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.18192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:23:09.676831Z","caller":"traceutil/trace.go:171","msg":"trace[2042110681] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1358; }","duration":"184.332173ms","start":"2026-07-30T19:23:09.492467Z","end":"2026-07-30T19:23:09.676799Z","steps":["trace[2042110681] 'agreement among raft nodes before linearized reading' (duration: 184.134468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.676825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.247651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T19:23:09.676896Z","caller":"traceutil/trace.go:171","msg":"trace[404757718] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"213.338403ms","start":"2026-07-30T19:23:09.46354Z","end":"2026-07-30T19:23:09.676878Z","steps":["trace[404757718] 'agreement among raft nodes before linearized reading' (duration: 213.089047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.677037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.054995ms","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-07-30T19:23:09.677051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.340814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:23:09.677081Z","caller":"traceutil/trace.go:171","msg":"trace[1940792396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"538.147067ms","start":"2026-07-30T19:23:09.138923Z","end":"2026-07-30T19:23:09.67707Z","steps":["trace[1940792396] 'agreement among raft nodes before linearized reading' (duration: 537.853858ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:09.677084Z","caller":"traceutil/trace.go:171","msg":"trace[20875066] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1358; }","duration":"216.412535ms","start":"2026-07-30T19:23:09.460662Z","end":"2026-07-30T19:23:09.677075Z","steps":["trace[20875066] 'agreement among raft nodes before linearized reading' (duration: 215.903601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:09.677109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:09.138903Z","time spent":"538.199428ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T19:23:11.871114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063956827,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:23:11.980036Z","caller":"traceutil/trace.go:171","msg":"trace[1087769333] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"710.048589ms","start":"2026-07-30T19:23:11.269961Z","end":"2026-07-30T19:23:11.98001Z","steps":["trace[1087769333] 'process raft request' (duration: 705.16637ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:11.980283Z","caller":"traceutil/trace.go:171","msg":"trace[2052699123] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1432; }","duration":"609.603584ms","start":"2026-07-30T19:23:11.370658Z","end":"2026-07-30T19:23:11.980262Z","steps":["trace[2052699123] 'read index received' (duration: 604.57781ms)","trace[2052699123] 'applied index is now lower than readState.Index' (duration: 5.024434ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:11.98034Z","caller":"traceutil/trace.go:171","msg":"trace[787429862] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"242.839386ms","start":"2026-07-30T19:23:11.737442Z","end":"2026-07-30T19:23:11.980281Z","steps":["trace[787429862] 'process raft request' (duration: 242.469016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:11.980541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.85242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-30T19:23:11.980554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.689233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T19:23:11.980592Z","caller":"traceutil/trace.go:171","msg":"trace[2011211621] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1386; }","duration":"609.967492ms","start":"2026-07-30T19:23:11.370606Z","end":"2026-07-30T19:23:11.980574Z","steps":["trace[2011211621] 'agreement among raft nodes before linearized reading' (duration: 609.792498ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:11.980614Z","caller":"traceutil/trace.go:171","msg":"trace[105593536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1386; }","duration":"271.747314ms","start":"2026-07-30T19:23:11.708849Z","end":"2026-07-30T19:23:11.980596Z","steps":["trace[105593536] 'agreement among raft nodes before linearized reading' (duration: 271.606861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:11.980602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.967021ms","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-07-30T19:23:11.980626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.590366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:23:11.980554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.155979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T19:23:11.980817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:11.269928Z","time spent":"710.80598ms","remote":"127.0.0.1:56836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:11.98067Z","caller":"traceutil/trace.go:171","msg":"trace[1699056150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1386; }","duration":"298.079314ms","start":"2026-07-30T19:23:11.682582Z","end":"2026-07-30T19:23:11.980661Z","steps":["trace[1699056150] 'agreement among raft nodes before linearized reading' (duration: 297.91798ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:11.980812Z","caller":"traceutil/trace.go:171","msg":"trace[1477783617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1386; }","duration":"300.456446ms","start":"2026-07-30T19:23:11.680337Z","end":"2026-07-30T19:23:11.980793Z","steps":["trace[1477783617] 'agreement among raft nodes before linearized reading' (duration: 300.098747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:11.980995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:11.680313Z","time spent":"300.663712ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T19:23:11.980732Z","caller":"traceutil/trace.go:171","msg":"trace[2094010669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"249.763261ms","start":"2026-07-30T19:23:11.730946Z","end":"2026-07-30T19:23:11.98071Z","steps":["trace[2094010669] 'agreement among raft nodes before linearized reading' (duration: 249.534035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:11.980625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:11.370582Z","time spent":"610.035244ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:23:11.98123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.258536ms","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-07-30T19:23:11.98133Z","caller":"traceutil/trace.go:171","msg":"trace[1895357703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1386; }","duration":"278.996496ms","start":"2026-07-30T19:23:11.702312Z","end":"2026-07-30T19:23:11.981308Z","steps":["trace[1895357703] 'agreement among raft nodes before linearized reading' (duration: 278.160634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:11.980736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.314241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-30T19:23:11.981441Z","caller":"traceutil/trace.go:171","msg":"trace[995086126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1386; }","duration":"272.057442ms","start":"2026-07-30T19:23:11.70937Z","end":"2026-07-30T19:23:11.981427Z","steps":["trace[995086126] 'agreement among raft nodes before linearized reading' (duration: 271.317142ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:31.319049Z","caller":"traceutil/trace.go:171","msg":"trace[2053247117] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"119.824782ms","start":"2026-07-30T19:23:31.199199Z","end":"2026-07-30T19:23:31.319024Z","steps":["trace[2053247117] 'process raft request' (duration: 119.643486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:32.696334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.15152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:23:32.696461Z","caller":"traceutil/trace.go:171","msg":"trace[939385543] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1661; }","duration":"262.301343ms","start":"2026-07-30T19:23:32.434133Z","end":"2026-07-30T19:23:32.696434Z","steps":["trace[939385543] 'count revisions from in-memory index tree' (duration: 262.077398ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:51.397039Z","caller":"traceutil/trace.go:171","msg":"trace[431872272] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"106.489738ms","start":"2026-07-30T19:23:51.290509Z","end":"2026-07-30T19:23:51.396999Z","steps":["trace[431872272] 'process raft request' (duration: 106.003755ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:58.890109Z","caller":"traceutil/trace.go:171","msg":"trace[826138008] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"634.046142ms","start":"2026-07-30T19:23:58.256032Z","end":"2026-07-30T19:23:58.890079Z","steps":["trace[826138008] 'process raft request' (duration: 618.776277ms)","trace[826138008] 'compare' (duration: 14.644259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:58.890263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.256018Z","time spent":"634.170185ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:59.073266Z","caller":"traceutil/trace.go:171","msg":"trace[766975641] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"817.199034ms","start":"2026-07-30T19:23:58.256045Z","end":"2026-07-30T19:23:59.073244Z","steps":["trace[766975641] 'process raft request' (duration: 780.383778ms)","trace[766975641] 'compare' (duration: 36.677543ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:59.073372Z","caller":"traceutil/trace.go:171","msg":"trace[903733273] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2205; }","duration":"402.148325ms","start":"2026-07-30T19:23:58.671213Z","end":"2026-07-30T19:23:59.073362Z","steps":["trace[903733273] 'read index received' (duration: 203.718208ms)","trace[903733273] 'applied index is now lower than readState.Index' (duration: 198.429497ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:59.07339Z","caller":"traceutil/trace.go:171","msg":"trace[883971632] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"817.260655ms","start":"2026-07-30T19:23:58.256105Z","end":"2026-07-30T19:23:59.073366Z","steps":["trace[883971632] 'process raft request' (duration: 817.110052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.073406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.256031Z","time spent":"817.306067ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:59.073436Z","caller":"traceutil/trace.go:171","msg":"trace[162038590] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"817.163643ms","start":"2026-07-30T19:23:58.256265Z","end":"2026-07-30T19:23:59.073429Z","steps":["trace[162038590] 'process raft request' (duration: 817.03601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.073479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.256255Z","time spent":"817.195353ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:59.073569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.205318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-30T19:23:59.073594Z","caller":"traceutil/trace.go:171","msg":"trace[625260041] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2128; }","duration":"341.278471ms","start":"2026-07-30T19:23:58.73231Z","end":"2026-07-30T19:23:59.073588Z","steps":["trace[625260041] 'agreement among raft nodes before linearized reading' (duration: 341.23222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.073613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.732285Z","time spent":"341.323501ms","remote":"127.0.0.1:56466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-07-30T19:23:59.073713Z","caller":"traceutil/trace.go:171","msg":"trace[1370174132] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"816.936778ms","start":"2026-07-30T19:23:58.256766Z","end":"2026-07-30T19:23:59.073703Z","steps":["trace[1370174132] 'process raft request' (duration: 816.571818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.073737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.503135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-30T19:23:59.073777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.256753Z","time spent":"816.994489ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:59.073737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.413669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:23:59.073821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.451075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:112225"} {"level":"info","ts":"2026-07-30T19:23:59.073853Z","caller":"traceutil/trace.go:171","msg":"trace[618673565] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2128; }","duration":"341.525496ms","start":"2026-07-30T19:23:58.732321Z","end":"2026-07-30T19:23:59.073846Z","steps":["trace[618673565] 'agreement among raft nodes before linearized reading' (duration: 341.396043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.073875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.732298Z","time spent":"341.572878ms","remote":"127.0.0.1:56464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":112249,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-30T19:23:59.073809Z","caller":"traceutil/trace.go:171","msg":"trace[1436585991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2128; }","duration":"402.622528ms","start":"2026-07-30T19:23:58.671168Z","end":"2026-07-30T19:23:59.07379Z","steps":["trace[1436585991] 'agreement among raft nodes before linearized reading' (duration: 402.346951ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:23:59.073866Z","caller":"traceutil/trace.go:171","msg":"trace[259002035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2128; }","duration":"325.561523ms","start":"2026-07-30T19:23:58.748276Z","end":"2026-07-30T19:23:59.073837Z","steps":["trace[259002035] 'agreement among raft nodes before linearized reading' (duration: 325.43603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.074007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.748255Z","time spent":"325.740617ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:23:59.073953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.671145Z","time spent":"402.793523ms","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-30T19:23:59.073499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:58.256078Z","time spent":"817.361768ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:23:59.704081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.644545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T19:23:59.70416Z","caller":"traceutil/trace.go:171","msg":"trace[1574558048] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2129; }","duration":"510.740698ms","start":"2026-07-30T19:23:59.193401Z","end":"2026-07-30T19:23:59.704142Z","steps":["trace[1574558048] 'range keys from in-memory index tree' (duration: 510.475562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.704204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:59.193337Z","time spent":"510.857221ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T19:23:59.704313Z","caller":"traceutil/trace.go:171","msg":"trace[1855329331] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"403.136751ms","start":"2026-07-30T19:23:59.301141Z","end":"2026-07-30T19:23:59.704278Z","steps":["trace[1855329331] 'process raft request' (duration: 371.952304ms)","trace[1855329331] 'compare' (duration: 31.011773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:23:59.704501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:59.30111Z","time spent":"403.296666ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:59.743675Z","caller":"traceutil/trace.go:171","msg":"trace[1992124484] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2211; }","duration":"122.77537ms","start":"2026-07-30T19:23:59.620861Z","end":"2026-07-30T19:23:59.743637Z","steps":["trace[1992124484] 'read index received' (duration: 52.244577ms)","trace[1992124484] 'applied index is now lower than readState.Index' (duration: 70.529633ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:23:59.743733Z","caller":"traceutil/trace.go:171","msg":"trace[458521920] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"319.563154ms","start":"2026-07-30T19:23:59.424115Z","end":"2026-07-30T19:23:59.743678Z","steps":["trace[458521920] 'process raft request' (duration: 319.371348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:23:59.743936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.060458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11911"} {"level":"warn","ts":"2026-07-30T19:23:59.743938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:23:59.424086Z","time spent":"319.731168ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:23:59.743987Z","caller":"traceutil/trace.go:171","msg":"trace[1297318287] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2131; }","duration":"123.1735ms","start":"2026-07-30T19:23:59.6208Z","end":"2026-07-30T19:23:59.743974Z","steps":["trace[1297318287] 'agreement among raft nodes before linearized reading' (duration: 122.971615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:05.290871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:04.90761Z","time spent":"383.252982ms","remote":"127.0.0.1:58524","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T19:24:05.291005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.711843ms","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-07-30T19:24:05.291098Z","caller":"traceutil/trace.go:171","msg":"trace[567314611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2179; }","duration":"366.881769ms","start":"2026-07-30T19:24:04.924197Z","end":"2026-07-30T19:24:05.291079Z","steps":["trace[567314611] 'range keys from in-memory index tree' (duration: 366.508378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:05.291152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:04.924168Z","time spent":"366.974661ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T19:24:05.29118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.421292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-30T19:24:05.291229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.764013ms","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-07-30T19:24:05.291246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.214237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T19:24:05.291266Z","caller":"traceutil/trace.go:171","msg":"trace[1766550308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2179; }","duration":"191.582236ms","start":"2026-07-30T19:24:05.099664Z","end":"2026-07-30T19:24:05.291246Z","steps":["trace[1766550308] 'range keys from in-memory index tree' (duration: 191.167804ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:05.291307Z","caller":"traceutil/trace.go:171","msg":"trace[672170762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2179; }","duration":"176.31213ms","start":"2026-07-30T19:24:05.114981Z","end":"2026-07-30T19:24:05.291293Z","steps":["trace[672170762] 'range keys from in-memory index tree' (duration: 176.058993ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:05.291306Z","caller":"traceutil/trace.go:171","msg":"trace[727744888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2179; }","duration":"193.887537ms","start":"2026-07-30T19:24:05.097398Z","end":"2026-07-30T19:24:05.291285Z","steps":["trace[727744888] 'range keys from in-memory index tree' (duration: 193.544327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:05.291432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.969375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T19:24:05.291462Z","caller":"traceutil/trace.go:171","msg":"trace[133921519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2179; }","duration":"192.029597ms","start":"2026-07-30T19:24:05.099427Z","end":"2026-07-30T19:24:05.291456Z","steps":["trace[133921519] 'range keys from in-memory index tree' (duration: 191.884223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:05.291386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.827415ms","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-07-30T19:24:05.291489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.682643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:24:05.291505Z","caller":"traceutil/trace.go:171","msg":"trace[464760635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2179; }","duration":"191.438551ms","start":"2026-07-30T19:24:05.100047Z","end":"2026-07-30T19:24:05.291485Z","steps":["trace[464760635] 'range keys from in-memory index tree' (duration: 190.65097ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:05.291546Z","caller":"traceutil/trace.go:171","msg":"trace[860723427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2179; }","duration":"340.783296ms","start":"2026-07-30T19:24:04.950746Z","end":"2026-07-30T19:24:05.291529Z","steps":["trace[860723427] 'range keys from in-memory index tree' (duration: 339.978724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:05.291588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:04.950701Z","time spent":"340.872058ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:24:05.292427Z","caller":"traceutil/trace.go:171","msg":"trace[1254778659] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"130.917315ms","start":"2026-07-30T19:24:05.161502Z","end":"2026-07-30T19:24:05.292419Z","steps":["trace[1254778659] 'process raft request' (duration: 130.825073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.234188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063958417,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:24:06.296697Z","caller":"traceutil/trace.go:171","msg":"trace[938641770] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"576.216855ms","start":"2026-07-30T19:24:05.72046Z","end":"2026-07-30T19:24:06.296676Z","steps":["trace[938641770] 'process raft request' (duration: 576.066721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.296831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.720438Z","time spent":"576.315617ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:06.419706Z","caller":"traceutil/trace.go:171","msg":"trace[2119720730] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"663.000998ms","start":"2026-07-30T19:24:05.756673Z","end":"2026-07-30T19:24:06.419674Z","steps":["trace[2119720730] 'process raft request' (duration: 662.743671ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:06.419749Z","caller":"traceutil/trace.go:171","msg":"trace[15705472] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"653.112536ms","start":"2026-07-30T19:24:05.766595Z","end":"2026-07-30T19:24:06.419708Z","steps":["trace[15705472] 'process raft request' (duration: 652.962631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.419849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.756649Z","time spent":"663.125802ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:06.419852Z","caller":"traceutil/trace.go:171","msg":"trace[1549549647] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"640.690836ms","start":"2026-07-30T19:24:05.779115Z","end":"2026-07-30T19:24:06.419805Z","steps":["trace[1549549647] 'process raft request' (duration: 640.47225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.419899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.766575Z","time spent":"653.2419ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:06.420018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.779082Z","time spent":"640.85221ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:06.424113Z","caller":"traceutil/trace.go:171","msg":"trace[438960775] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2268; }","duration":"690.815047ms","start":"2026-07-30T19:24:05.733275Z","end":"2026-07-30T19:24:06.42409Z","steps":["trace[438960775] 'read index received' (duration: 563.331013ms)","trace[438960775] 'applied index is now lower than readState.Index' (duration: 127.482674ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:24:06.424266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.977481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:24:06.424341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.886276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T19:24:06.42438Z","caller":"traceutil/trace.go:171","msg":"trace[1210790171] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2189; }","duration":"653.961178ms","start":"2026-07-30T19:24:05.770408Z","end":"2026-07-30T19:24:06.424369Z","steps":["trace[1210790171] 'agreement among raft nodes before linearized reading' (duration: 653.813324ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:06.424376Z","caller":"traceutil/trace.go:171","msg":"trace[995010180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2189; }","duration":"691.093554ms","start":"2026-07-30T19:24:05.733226Z","end":"2026-07-30T19:24:06.42432Z","steps":["trace[995010180] 'agreement among raft nodes before linearized reading' (duration: 690.96257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.4244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.574503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-30T19:24:06.424416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.7704Z","time spent":"654.008549ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T19:24:06.424426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.733207Z","time spent":"691.208946ms","remote":"127.0.0.1:56420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T19:24:06.424444Z","caller":"traceutil/trace.go:171","msg":"trace[1875047998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"125.652264ms","start":"2026-07-30T19:24:06.298781Z","end":"2026-07-30T19:24:06.424434Z","steps":["trace[1875047998] 'agreement among raft nodes before linearized reading' (duration: 125.48616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.424497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.935119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-30T19:24:06.424569Z","caller":"traceutil/trace.go:171","msg":"trace[847019697] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2189; }","duration":"448.030942ms","start":"2026-07-30T19:24:05.976512Z","end":"2026-07-30T19:24:06.424543Z","steps":["trace[847019697] 'agreement among raft nodes before linearized reading' (duration: 447.819736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:06.42461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:05.976488Z","time spent":"448.114984ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:24:17.148743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.418122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:24:17.148928Z","caller":"traceutil/trace.go:171","msg":"trace[1964399873] linearizableReadLoop","detail":"{readStateIndex:2357; appliedIndex:2355; }","duration":"624.319872ms","start":"2026-07-30T19:24:16.524593Z","end":"2026-07-30T19:24:17.148913Z","steps":["trace[1964399873] 'read index received' (duration: 82.623584ms)","trace[1964399873] 'applied index is now lower than readState.Index' (duration: 541.695588ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:24:17.149009Z","caller":"traceutil/trace.go:171","msg":"trace[1671114057] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"642.988727ms","start":"2026-07-30T19:24:16.506013Z","end":"2026-07-30T19:24:17.149001Z","steps":["trace[1671114057] 'process raft request' (duration: 642.830873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:17.149089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:16.505994Z","time spent":"643.037667ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:17.149146Z","caller":"traceutil/trace.go:171","msg":"trace[841855566] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"644.059746ms","start":"2026-07-30T19:24:16.505022Z","end":"2026-07-30T19:24:17.149082Z","steps":["trace[841855566] 'process raft request' (duration: 102.223283ms)","trace[841855566] 'compare' (duration: 541.254927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:24:17.149233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.66724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:24:17.149294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:16.504999Z","time spent":"644.216809ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:17.149313Z","caller":"traceutil/trace.go:171","msg":"trace[1581528534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2270; }","duration":"416.810294ms","start":"2026-07-30T19:24:16.732485Z","end":"2026-07-30T19:24:17.149295Z","steps":["trace[1581528534] 'agreement among raft nodes before linearized reading' (duration: 416.634589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:17.149344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:16.732463Z","time spent":"416.875586ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:24:17.149412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.784545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T19:24:17.14952Z","caller":"traceutil/trace.go:171","msg":"trace[1730538196] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2270; }","duration":"624.956249ms","start":"2026-07-30T19:24:16.524538Z","end":"2026-07-30T19:24:17.149494Z","steps":["trace[1730538196] 'agreement among raft nodes before linearized reading' (duration: 624.65286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:17.14957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:16.524514Z","time spent":"625.044782ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T19:24:28.727191Z","caller":"traceutil/trace.go:171","msg":"trace[609447261] transaction","detail":"{read_only:false; response_revision:2358; number_of_response:1; }","duration":"146.008495ms","start":"2026-07-30T19:24:28.581146Z","end":"2026-07-30T19:24:28.727154Z","steps":["trace[609447261] 'process raft request' (duration: 145.700147ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:28.729766Z","caller":"traceutil/trace.go:171","msg":"trace[1308343674] linearizableReadLoop","detail":"{readStateIndex:2448; appliedIndex:2446; }","duration":"108.011497ms","start":"2026-07-30T19:24:28.621724Z","end":"2026-07-30T19:24:28.729736Z","steps":["trace[1308343674] 'read index received' (duration: 105.10898ms)","trace[1308343674] 'applied index is now lower than readState.Index' (duration: 2.901287ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:24:28.729961Z","caller":"traceutil/trace.go:171","msg":"trace[1103662051] transaction","detail":"{read_only:false; response_revision:2359; number_of_response:1; }","duration":"135.166188ms","start":"2026-07-30T19:24:28.594671Z","end":"2026-07-30T19:24:28.729838Z","steps":["trace[1103662051] 'process raft request' (duration: 134.859021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:28.730051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.313605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T19:24:28.730136Z","caller":"traceutil/trace.go:171","msg":"trace[843674971] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2359; }","duration":"108.464579ms","start":"2026-07-30T19:24:28.621648Z","end":"2026-07-30T19:24:28.730112Z","steps":["trace[843674971] 'agreement among raft nodes before linearized reading' (duration: 108.217573ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:34.095121Z","caller":"traceutil/trace.go:171","msg":"trace[1190093063] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"121.823514ms","start":"2026-07-30T19:24:33.973275Z","end":"2026-07-30T19:24:34.095099Z","steps":["trace[1190093063] 'process raft request' (duration: 121.664949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:35.364005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.562025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12632"} {"level":"info","ts":"2026-07-30T19:24:35.364165Z","caller":"traceutil/trace.go:171","msg":"trace[1191661474] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2396; }","duration":"347.791611ms","start":"2026-07-30T19:24:35.016341Z","end":"2026-07-30T19:24:35.364133Z","steps":["trace[1191661474] 'range keys from in-memory index tree' (duration: 347.226856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:35.364233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.016315Z","time spent":"347.892644ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12656,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-30T19:24:35.364354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.147782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T19:24:35.364468Z","caller":"traceutil/trace.go:171","msg":"trace[804278321] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2396; }","duration":"455.298786ms","start":"2026-07-30T19:24:34.909137Z","end":"2026-07-30T19:24:35.364436Z","steps":["trace[804278321] 'range keys from in-memory index tree' (duration: 454.868003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:35.364546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:34.90912Z","time spent":"455.410869ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:24:36.136098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063958895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:24:37.686425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497437207s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T19:24:37.686819Z","caller":"traceutil/trace.go:171","msg":"trace[1459476841] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"2.124796848s","start":"2026-07-30T19:24:35.561989Z","end":"2026-07-30T19:24:37.686786Z","steps":["trace[1459476841] 'process raft request' (duration: 2.124552352s)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:37.686813Z","caller":"traceutil/trace.go:171","msg":"trace[2050918789] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"2.124753298s","start":"2026-07-30T19:24:35.562037Z","end":"2026-07-30T19:24:37.686791Z","steps":["trace[2050918789] 'process raft request' (duration: 2.124581843s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.68692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.562019Z","time spent":"2.124868731s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:37.686943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.561972Z","time spent":"2.124909142s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:37.686811Z","caller":"traceutil/trace.go:171","msg":"trace[1431344986] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"2.124784059s","start":"2026-07-30T19:24:35.561974Z","end":"2026-07-30T19:24:37.686758Z","steps":["trace[1431344986] 'process raft request' (duration: 626.839978ms)","trace[1431344986] 'compare' (duration: 1.497273823s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:24:37.689772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.561949Z","time spent":"2.127757727s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:37.696036Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.03754072s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T19:24:37.732648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99980939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-30T19:24:37.732735Z","caller":"traceutil/trace.go:171","msg":"trace[1633854977] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999944634s","start":"2026-07-30T19:24:35.732768Z","end":"2026-07-30T19:24:37.732713Z","steps":["trace[1633854977] 'agreement among raft nodes before linearized reading' (duration: 1.999842151s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.732782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.732748Z","time spent":"2.000024207s","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/30 19:24:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-30T19:24:37.82704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.306034ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T19:24:37.827321Z","caller":"traceutil/trace.go:171","msg":"trace[973608096] linearizableReadLoop","detail":"{readStateIndex:2495; appliedIndex:2491; }","duration":"2.192055085s","start":"2026-07-30T19:24:35.635239Z","end":"2026-07-30T19:24:37.827294Z","steps":["trace[973608096] 'read index received' (duration: 553.561134ms)","trace[973608096] 'applied index is now lower than readState.Index' (duration: 1.638491411s)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:24:37.827428Z","caller":"traceutil/trace.go:171","msg":"trace[518630636] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"1.374446212s","start":"2026-07-30T19:24:36.452963Z","end":"2026-07-30T19:24:37.827409Z","steps":["trace[518630636] 'process raft request' (duration: 1.37435758s)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:37.827449Z","caller":"traceutil/trace.go:171","msg":"trace[296938405] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"1.405569888s","start":"2026-07-30T19:24:36.421842Z","end":"2026-07-30T19:24:37.827412Z","steps":["trace[296938405] 'process raft request' (duration: 1.40531448s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.827605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:36.452941Z","time spent":"1.374586706s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:37.827622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.192383563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"warn","ts":"2026-07-30T19:24:37.82764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:36.421818Z","time spent":"1.405726562s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:37.827681Z","caller":"traceutil/trace.go:171","msg":"trace[1817333777] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2406; }","duration":"2.192478225s","start":"2026-07-30T19:24:35.635187Z","end":"2026-07-30T19:24:37.827665Z","steps":["trace[1817333777] 'agreement among raft nodes before linearized reading' (duration: 2.192224589s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.827729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.635167Z","time spent":"2.192550337s","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2014,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-07-30T19:24:37.862809Z","caller":"traceutil/trace.go:171","msg":"trace[954063323] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"1.015031252s","start":"2026-07-30T19:24:36.847747Z","end":"2026-07-30T19:24:37.862778Z","steps":["trace[954063323] 'process raft request' (duration: 1.014826227s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.86301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:36.847728Z","time spent":"1.015166956s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:37.863135Z","caller":"traceutil/trace.go:171","msg":"trace[479652430] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"984.533163ms","start":"2026-07-30T19:24:36.878572Z","end":"2026-07-30T19:24:37.863105Z","steps":["trace[479652430] 'process raft request' (duration: 984.129222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:36.878542Z","time spent":"984.649355ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:37.863375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029842307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T19:24:37.863453Z","caller":"traceutil/trace.go:171","msg":"trace[1857829289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2410; }","duration":"2.02996964s","start":"2026-07-30T19:24:35.833465Z","end":"2026-07-30T19:24:37.863434Z","steps":["trace[1857829289] 'agreement among raft nodes before linearized reading' (duration: 2.029762375s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.748648ms","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-07-30T19:24:37.863487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.17473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-30T19:24:37.863513Z","caller":"traceutil/trace.go:171","msg":"trace[1799606629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2410; }","duration":"287.840949ms","start":"2026-07-30T19:24:37.575659Z","end":"2026-07-30T19:24:37.863499Z","steps":["trace[1799606629] 'agreement among raft nodes before linearized reading' (duration: 287.691345ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:37.863519Z","caller":"traceutil/trace.go:171","msg":"trace[1730034422] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"325.576371ms","start":"2026-07-30T19:24:37.537933Z","end":"2026-07-30T19:24:37.863509Z","steps":["trace[1730034422] 'process raft request' (duration: 325.228683ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:37.863538Z","caller":"traceutil/trace.go:171","msg":"trace[1168003326] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2410; }","duration":"652.265422ms","start":"2026-07-30T19:24:37.21126Z","end":"2026-07-30T19:24:37.863525Z","steps":["trace[1168003326] 'agreement among raft nodes before linearized reading' (duration: 652.147399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.537923Z","time spent":"325.619652ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:37.863577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.211237Z","time spent":"652.331554ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:24:37.863587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.856731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T19:24:37.863639Z","caller":"traceutil/trace.go:171","msg":"trace[2027719104] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"348.848028ms","start":"2026-07-30T19:24:37.514784Z","end":"2026-07-30T19:24:37.863633Z","steps":["trace[2027719104] 'process raft request' (duration: 348.262294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.514764Z","time spent":"348.896431ms","remote":"127.0.0.1:56558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10013,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:37.863695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.455608ms","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-07-30T19:24:37.863722Z","caller":"traceutil/trace.go:171","msg":"trace[1470241072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2410; }","duration":"302.518571ms","start":"2026-07-30T19:24:37.561196Z","end":"2026-07-30T19:24:37.863714Z","steps":["trace[1470241072] 'agreement among raft nodes before linearized reading' (duration: 302.444328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.561175Z","time spent":"302.573811ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T19:24:37.863776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.719606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-30T19:24:37.863636Z","caller":"traceutil/trace.go:171","msg":"trace[74844739] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2410; }","duration":"677.932523ms","start":"2026-07-30T19:24:37.185694Z","end":"2026-07-30T19:24:37.863626Z","steps":["trace[74844739] 'agreement among raft nodes before linearized reading' (duration: 677.829661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.888444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:24:37.863816Z","caller":"traceutil/trace.go:171","msg":"trace[1441393189] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2410; }","duration":"678.796917ms","start":"2026-07-30T19:24:37.185008Z","end":"2026-07-30T19:24:37.863805Z","steps":["trace[1441393189] 'agreement among raft nodes before linearized reading' (duration: 678.693864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:35.833443Z","time spent":"2.030048293s","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-30T19:24:37.863836Z","caller":"traceutil/trace.go:171","msg":"trace[266403125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2410; }","duration":"130.973426ms","start":"2026-07-30T19:24:37.732847Z","end":"2026-07-30T19:24:37.86382Z","steps":["trace[266403125] 'agreement among raft nodes before linearized reading' (duration: 130.897794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.863855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.184988Z","time spent":"678.857869ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:24:37.864013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.689608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-30T19:24:37.864043Z","caller":"traceutil/trace.go:171","msg":"trace[1402504980] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2410; }","duration":"460.75523ms","start":"2026-07-30T19:24:37.40328Z","end":"2026-07-30T19:24:37.864036Z","steps":["trace[1402504980] 'agreement among raft nodes before linearized reading' (duration: 460.655847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:37.864065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.40326Z","time spent":"460.79918ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T19:24:37.864068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.973041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T19:24:37.863825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.185676Z","time spent":"678.13853ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T19:24:37.864142Z","caller":"traceutil/trace.go:171","msg":"trace[1027062829] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2410; }","duration":"173.082644ms","start":"2026-07-30T19:24:37.691043Z","end":"2026-07-30T19:24:37.864126Z","steps":["trace[1027062829] 'agreement among raft nodes before linearized reading' (duration: 172.93285ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:38.266005Z","caller":"traceutil/trace.go:171","msg":"trace[1887345732] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"341.076923ms","start":"2026-07-30T19:24:37.924905Z","end":"2026-07-30T19:24:38.265982Z","steps":["trace[1887345732] 'process raft request' (duration: 341.038902ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:38.266143Z","caller":"traceutil/trace.go:171","msg":"trace[1204248749] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"396.519395ms","start":"2026-07-30T19:24:37.869599Z","end":"2026-07-30T19:24:38.266119Z","steps":["trace[1204248749] 'process raft request' (duration: 396.275879ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:38.266173Z","caller":"traceutil/trace.go:171","msg":"trace[1993500926] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"396.523555ms","start":"2026-07-30T19:24:37.869625Z","end":"2026-07-30T19:24:38.266149Z","steps":["trace[1993500926] 'process raft request' (duration: 396.281958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:38.266172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.924878Z","time spent":"341.218926ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:38.266272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.869581Z","time spent":"396.619887ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:38.266274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.869611Z","time spent":"396.609707ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:38.266337Z","caller":"traceutil/trace.go:171","msg":"trace[1448725377] linearizableReadLoop","detail":"{readStateIndex:2508; appliedIndex:2502; }","duration":"392.489149ms","start":"2026-07-30T19:24:37.873822Z","end":"2026-07-30T19:24:38.266311Z","steps":["trace[1448725377] 'read index received' (duration: 12.502362ms)","trace[1448725377] 'applied index is now lower than readState.Index' (duration: 379.983277ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:24:38.266391Z","caller":"traceutil/trace.go:171","msg":"trace[1333506324] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"396.899515ms","start":"2026-07-30T19:24:37.869482Z","end":"2026-07-30T19:24:38.266382Z","steps":["trace[1333506324] 'process raft request' (duration: 396.357301ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:38.266391Z","caller":"traceutil/trace.go:171","msg":"trace[1565548000] transaction","detail":"{read_only:false; response_revision:2413; number_of_response:1; }","duration":"397.778458ms","start":"2026-07-30T19:24:37.868602Z","end":"2026-07-30T19:24:38.266381Z","steps":["trace[1565548000] 'process raft request' (duration: 397.136251ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:38.266389Z","caller":"traceutil/trace.go:171","msg":"trace[944326881] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"397.773198ms","start":"2026-07-30T19:24:37.868596Z","end":"2026-07-30T19:24:38.266369Z","steps":["trace[944326881] 'process raft request' (duration: 334.93198ms)","trace[944326881] 'compare' (duration: 62.081858ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:24:38.266147Z","caller":"traceutil/trace.go:171","msg":"trace[1981926883] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"397.464819ms","start":"2026-07-30T19:24:37.868639Z","end":"2026-07-30T19:24:38.266104Z","steps":["trace[1981926883] 'process raft request' (duration: 397.156372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:38.266451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.868576Z","time spent":"397.840309ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:38.266492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.868556Z","time spent":"397.878061ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:38.26644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.869469Z","time spent":"396.943496ms","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T19:24:38.266584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.731085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:24:38.266683Z","caller":"traceutil/trace.go:171","msg":"trace[2136459169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2418; }","duration":"392.879888ms","start":"2026-07-30T19:24:37.87378Z","end":"2026-07-30T19:24:38.26666Z","steps":["trace[2136459169] 'agreement among raft nodes before linearized reading' (duration: 392.697864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:38.266769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.873735Z","time spent":"393.020582ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:24:38.267002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.946311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:24:38.267057Z","caller":"traceutil/trace.go:171","msg":"trace[1085428857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2418; }","duration":"118.056724ms","start":"2026-07-30T19:24:38.148986Z","end":"2026-07-30T19:24:38.267042Z","steps":["trace[1085428857] 'agreement among raft nodes before linearized reading' (duration: 117.473598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:38.267001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:37.868618Z","time spent":"397.934272ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:24:39.500361Z","caller":"traceutil/trace.go:171","msg":"trace[1952452237] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"398.002034ms","start":"2026-07-30T19:24:39.102345Z","end":"2026-07-30T19:24:39.500347Z","steps":["trace[1952452237] 'process raft request' (duration: 397.812209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:24:39.500508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:24:39.102315Z","time spent":"398.106236ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:24:39.500563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.267289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:24:39.500639Z","caller":"traceutil/trace.go:171","msg":"trace[355060149] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2419; }","duration":"156.411682ms","start":"2026-07-30T19:24:39.344209Z","end":"2026-07-30T19:24:39.50062Z","steps":["trace[355060149] 'agreement among raft nodes before linearized reading' (duration: 156.247737ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:24:39.500674Z","caller":"traceutil/trace.go:171","msg":"trace[190803565] linearizableReadLoop","detail":"{readStateIndex:2510; appliedIndex:2509; }","duration":"156.049973ms","start":"2026-07-30T19:24:39.344257Z","end":"2026-07-30T19:24:39.500307Z","steps":["trace[190803565] 'read index received' (duration: 155.771326ms)","trace[190803565] 'applied index is now lower than readState.Index' (duration: 277.907µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:24:39.500811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.134678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:24:39.500842Z","caller":"traceutil/trace.go:171","msg":"trace[648030370] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2419; }","duration":"152.2099ms","start":"2026-07-30T19:24:39.348624Z","end":"2026-07-30T19:24:39.500834Z","steps":["trace[648030370] 'agreement among raft nodes before linearized reading' (duration: 152.109907ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:12.90243Z","caller":"traceutil/trace.go:171","msg":"trace[1797267050] transaction","detail":"{read_only:false; response_revision:3089; number_of_response:1; }","duration":"176.059425ms","start":"2026-07-30T19:26:12.726348Z","end":"2026-07-30T19:26:12.902408Z","steps":["trace[1797267050] 'process raft request' (duration: 175.885221ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:12.903948Z","caller":"traceutil/trace.go:171","msg":"trace[50989034] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"172.661235ms","start":"2026-07-30T19:26:12.731253Z","end":"2026-07-30T19:26:12.903914Z","steps":["trace[50989034] 'process raft request' (duration: 172.597953ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:12.904044Z","caller":"traceutil/trace.go:171","msg":"trace[755765774] transaction","detail":"{read_only:false; response_revision:3090; number_of_response:1; }","duration":"173.001164ms","start":"2026-07-30T19:26:12.731026Z","end":"2026-07-30T19:26:12.904027Z","steps":["trace[755765774] 'process raft request' (duration: 172.651305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:13.777404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063960464,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:26:14.035532Z","caller":"traceutil/trace.go:171","msg":"trace[418215079] linearizableReadLoop","detail":"{readStateIndex:3203; appliedIndex:3202; }","duration":"758.537481ms","start":"2026-07-30T19:26:13.276969Z","end":"2026-07-30T19:26:14.035506Z","steps":["trace[418215079] 'read index received' (duration: 758.356687ms)","trace[418215079] 'applied index is now lower than readState.Index' (duration: 179.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:26:14.035637Z","caller":"traceutil/trace.go:171","msg":"trace[1511725439] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"966.549784ms","start":"2026-07-30T19:26:13.069056Z","end":"2026-07-30T19:26:14.035605Z","steps":["trace[1511725439] 'process raft request' (duration: 966.294077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.035748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.221184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:26:14.035794Z","caller":"traceutil/trace.go:171","msg":"trace[686553834] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:3092; }","duration":"720.313176ms","start":"2026-07-30T19:26:13.31547Z","end":"2026-07-30T19:26:14.035783Z","steps":["trace[686553834] 'agreement among raft nodes before linearized reading' (duration: 720.218774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.035783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.776588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T19:26:14.035815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.244945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:26:14.035842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.315459Z","time spent":"720.368907ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:26:14.03585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.069031Z","time spent":"966.725089ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:26:14.035877Z","caller":"traceutil/trace.go:171","msg":"trace[571171006] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:3092; }","duration":"758.912611ms","start":"2026-07-30T19:26:13.27694Z","end":"2026-07-30T19:26:14.035853Z","steps":["trace[571171006] 'agreement among raft nodes before linearized reading' (duration: 758.735556ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:14.035882Z","caller":"traceutil/trace.go:171","msg":"trace[183939389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3092; }","duration":"305.341267ms","start":"2026-07-30T19:26:13.730522Z","end":"2026-07-30T19:26:14.035864Z","steps":["trace[183939389] 'agreement among raft nodes before linearized reading' (duration: 305.217394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.03593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.730507Z","time spent":"305.411639ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:26:14.03592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.583406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:26:14.035943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.276926Z","time spent":"759.005234ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:26:14.036Z","caller":"traceutil/trace.go:171","msg":"trace[435926507] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:3092; }","duration":"264.714229ms","start":"2026-07-30T19:26:13.771264Z","end":"2026-07-30T19:26:14.035978Z","steps":["trace[435926507] 'agreement among raft nodes before linearized reading' (duration: 264.578506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.036132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.867695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-30T19:26:14.036198Z","caller":"traceutil/trace.go:171","msg":"trace[1614000654] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3092; }","duration":"565.967257ms","start":"2026-07-30T19:26:13.47021Z","end":"2026-07-30T19:26:14.036178Z","steps":["trace[1614000654] 'agreement among raft nodes before linearized reading' (duration: 565.798543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.03626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.470179Z","time spent":"566.0636ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:26:14.036207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.929568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T19:26:14.036382Z","caller":"traceutil/trace.go:171","msg":"trace[1120417360] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3092; }","duration":"586.123513ms","start":"2026-07-30T19:26:13.450243Z","end":"2026-07-30T19:26:14.036366Z","steps":["trace[1120417360] 'agreement among raft nodes before linearized reading' (duration: 585.867936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.036424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.450226Z","time spent":"586.185535ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:26:14.036468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.921105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12462"} {"level":"info","ts":"2026-07-30T19:26:14.036505Z","caller":"traceutil/trace.go:171","msg":"trace[1495693591] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3092; }","duration":"430.001268ms","start":"2026-07-30T19:26:13.606493Z","end":"2026-07-30T19:26:14.036494Z","steps":["trace[1495693591] 'agreement among raft nodes before linearized reading' (duration: 429.846233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:14.036535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:26:13.606473Z","time spent":"430.05567ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12486,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-30T19:26:50.495557Z","caller":"traceutil/trace.go:171","msg":"trace[713774677] transaction","detail":"{read_only:false; response_revision:3339; number_of_response:1; }","duration":"160.076261ms","start":"2026-07-30T19:26:50.33545Z","end":"2026-07-30T19:26:50.495526Z","steps":["trace[713774677] 'process raft request' (duration: 154.198115ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:50.51506Z","caller":"traceutil/trace.go:171","msg":"trace[1278150065] linearizableReadLoop","detail":"{readStateIndex:3460; appliedIndex:3457; }","duration":"169.745357ms","start":"2026-07-30T19:26:50.3453Z","end":"2026-07-30T19:26:50.515045Z","steps":["trace[1278150065] 'read index received' (duration: 144.341073ms)","trace[1278150065] 'applied index is now lower than readState.Index' (duration: 25.402954ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:26:50.515281Z","caller":"traceutil/trace.go:171","msg":"trace[320864645] transaction","detail":"{read_only:false; response_revision:3341; number_of_response:1; }","duration":"178.168361ms","start":"2026-07-30T19:26:50.337037Z","end":"2026-07-30T19:26:50.515205Z","steps":["trace[320864645] 'process raft request' (duration: 177.817772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:50.515316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.982763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T19:26:50.515419Z","caller":"traceutil/trace.go:171","msg":"trace[326784919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3341; }","duration":"170.140037ms","start":"2026-07-30T19:26:50.345261Z","end":"2026-07-30T19:26:50.515401Z","steps":["trace[326784919] 'agreement among raft nodes before linearized reading' (duration: 169.908412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:26:50.515339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.954993ms","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-07-30T19:26:50.51543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.520895ms","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-07-30T19:26:50.515513Z","caller":"traceutil/trace.go:171","msg":"trace[1504307441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3341; }","duration":"165.650538ms","start":"2026-07-30T19:26:50.349845Z","end":"2026-07-30T19:26:50.515496Z","steps":["trace[1504307441] 'agreement among raft nodes before linearized reading' (duration: 165.401742ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:50.514988Z","caller":"traceutil/trace.go:171","msg":"trace[1869025985] transaction","detail":"{read_only:false; response_revision:3340; number_of_response:1; }","duration":"177.948625ms","start":"2026-07-30T19:26:50.33701Z","end":"2026-07-30T19:26:50.514958Z","steps":["trace[1869025985] 'process raft request' (duration: 177.688118ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:26:50.515546Z","caller":"traceutil/trace.go:171","msg":"trace[1598164584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3341; }","duration":"170.140248ms","start":"2026-07-30T19:26:50.345348Z","end":"2026-07-30T19:26:50.515488Z","steps":["trace[1598164584] 'agreement among raft nodes before linearized reading' (duration: 169.876151ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:30:57.441035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-07-30T19:30:57.521622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"79.725373ms","hash":214896871,"current-db-size-bytes":34357248,"current-db-size":"34 MB","current-db-size-in-use-bytes":10256384,"current-db-size-in-use":"10 MB"} {"level":"info","ts":"2026-07-30T19:30:57.521699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":214896871,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-07-30T19:32:15.631824Z","caller":"traceutil/trace.go:171","msg":"trace[1147770999] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"175.031776ms","start":"2026-07-30T19:32:15.456764Z","end":"2026-07-30T19:32:15.631796Z","steps":["trace[1147770999] 'process raft request' (duration: 174.86209ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:32:18.464325Z","caller":"traceutil/trace.go:171","msg":"trace[1302781343] linearizableReadLoop","detail":"{readStateIndex:5626; appliedIndex:5625; }","duration":"221.47904ms","start":"2026-07-30T19:32:18.242809Z","end":"2026-07-30T19:32:18.464288Z","steps":["trace[1302781343] 'read index received' (duration: 221.162123ms)","trace[1302781343] 'applied index is now lower than readState.Index' (duration: 315.177µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:32:18.464373Z","caller":"traceutil/trace.go:171","msg":"trace[1247442402] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"229.726659ms","start":"2026-07-30T19:32:18.234611Z","end":"2026-07-30T19:32:18.464337Z","steps":["trace[1247442402] 'process raft request' (duration: 229.403861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:32:18.464648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.795669ms","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-07-30T19:32:18.464748Z","caller":"traceutil/trace.go:171","msg":"trace[2097858730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5438; }","duration":"221.949223ms","start":"2026-07-30T19:32:18.242777Z","end":"2026-07-30T19:32:18.464726Z","steps":["trace[2097858730] 'agreement among raft nodes before linearized reading' (duration: 221.645366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:32:18.464858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.01543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T19:32:18.464995Z","caller":"traceutil/trace.go:171","msg":"trace[514923631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5438; }","duration":"146.187395ms","start":"2026-07-30T19:32:18.318762Z","end":"2026-07-30T19:32:18.464949Z","steps":["trace[514923631] 'agreement among raft nodes before linearized reading' (duration: 145.797725ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:32:18.632104Z","caller":"traceutil/trace.go:171","msg":"trace[1222743473] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"162.356432ms","start":"2026-07-30T19:32:18.469716Z","end":"2026-07-30T19:32:18.632072Z","steps":["trace[1222743473] 'process raft request' (duration: 162.300071ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:32:18.632416Z","caller":"traceutil/trace.go:171","msg":"trace[52333531] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"163.770509ms","start":"2026-07-30T19:32:18.468629Z","end":"2026-07-30T19:32:18.632399Z","steps":["trace[52333531] 'process raft request' (duration: 142.865798ms)","trace[52333531] 'compare' (duration: 20.384587ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:33:15.324808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063967683,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:33:15.794012Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.02888176s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T19:33:15.794398Z","caller":"traceutil/trace.go:171","msg":"trace[1950280017] linearizableReadLoop","detail":"{readStateIndex:5992; appliedIndex:5991; }","duration":"970.061963ms","start":"2026-07-30T19:33:14.824306Z","end":"2026-07-30T19:33:15.794368Z","steps":["trace[1950280017] 'read index received' (duration: 969.901949ms)","trace[1950280017] 'applied index is now lower than readState.Index' (duration: 158.764µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:33:15.79448Z","caller":"traceutil/trace.go:171","msg":"trace[1784190288] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"1.029402005s","start":"2026-07-30T19:33:14.765016Z","end":"2026-07-30T19:33:15.794418Z","steps":["trace[1784190288] 'process raft request' (duration: 1.029186638s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.794598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.24947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:33:15.794644Z","caller":"traceutil/trace.go:171","msg":"trace[291333309] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:5793; }","duration":"970.363972ms","start":"2026-07-30T19:33:14.824267Z","end":"2026-07-30T19:33:15.794631Z","steps":["trace[291333309] 'agreement among raft nodes before linearized reading' (duration: 970.230749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.794679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.764999Z","time spent":"1.029560628s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:33:15.794708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.824253Z","time spent":"970.425104ms","remote":"127.0.0.1:56706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":24,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:33:15.799939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.795393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-30T19:33:15.800006Z","caller":"traceutil/trace.go:171","msg":"trace[242225743] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:5793; }","duration":"883.904865ms","start":"2026-07-30T19:33:14.916085Z","end":"2026-07-30T19:33:15.799989Z","steps":["trace[242225743] 'agreement among raft nodes before linearized reading' (duration: 883.724411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.916067Z","time spent":"883.964808ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T19:33:15.800155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.063229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T19:33:15.800181Z","caller":"traceutil/trace.go:171","msg":"trace[303198539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5793; }","duration":"800.122971ms","start":"2026-07-30T19:33:15.000052Z","end":"2026-07-30T19:33:15.800175Z","steps":["trace[303198539] 'agreement among raft nodes before linearized reading' (duration: 800.059919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:15.000036Z","time spent":"800.164261ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T19:33:15.800023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.944045ms","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-07-30T19:33:15.800321Z","caller":"traceutil/trace.go:171","msg":"trace[1953542946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5793; }","duration":"769.271684ms","start":"2026-07-30T19:33:15.03101Z","end":"2026-07-30T19:33:15.800282Z","steps":["trace[1953542946] 'agreement among raft nodes before linearized reading' (duration: 768.818892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.95007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:33:15.800449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:15.030994Z","time spent":"769.441169ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T19:33:15.800499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.120658ms","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-07-30T19:33:15.800499Z","caller":"traceutil/trace.go:171","msg":"trace[1590654205] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5793; }","duration":"163.123724ms","start":"2026-07-30T19:33:15.63735Z","end":"2026-07-30T19:33:15.800474Z","steps":["trace[1590654205] 'agreement among raft nodes before linearized reading' (duration: 162.95775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.334983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T19:33:15.800619Z","caller":"traceutil/trace.go:171","msg":"trace[48948701] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:5793; }","duration":"767.432145ms","start":"2026-07-30T19:33:15.033169Z","end":"2026-07-30T19:33:15.800601Z","steps":["trace[48948701] 'agreement among raft nodes before linearized reading' (duration: 767.20936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.421214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-07-30T19:33:15.800651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:15.033162Z","time spent":"767.484007ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T19:33:15.80066Z","caller":"traceutil/trace.go:171","msg":"trace[1932470919] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:5793; }","duration":"832.63445ms","start":"2026-07-30T19:33:14.968018Z","end":"2026-07-30T19:33:15.800652Z","steps":["trace[1932470919] 'agreement among raft nodes before linearized reading' (duration: 832.377273ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:33:15.800526Z","caller":"traceutil/trace.go:171","msg":"trace[330934295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5793; }","duration":"871.157118ms","start":"2026-07-30T19:33:14.929362Z","end":"2026-07-30T19:33:15.800519Z","steps":["trace[330934295] 'agreement among raft nodes before linearized reading' (duration: 871.097017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.967962Z","time spent":"832.718231ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-30T19:33:15.800575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.664739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-30T19:33:15.800758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.911585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-30T19:33:15.800754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.929357Z","time spent":"871.352484ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T19:33:15.800841Z","caller":"traceutil/trace.go:171","msg":"trace[1366576167] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:5793; }","duration":"873.035909ms","start":"2026-07-30T19:33:14.92778Z","end":"2026-07-30T19:33:15.800816Z","steps":["trace[1366576167] 'agreement among raft nodes before linearized reading' (duration: 872.831393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.800899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.927764Z","time spent":"873.12056ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T19:33:15.800856Z","caller":"traceutil/trace.go:171","msg":"trace[1649770537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5793; }","duration":"872.890995ms","start":"2026-07-30T19:33:14.927893Z","end":"2026-07-30T19:33:15.800784Z","steps":["trace[1649770537] 'agreement among raft nodes before linearized reading' (duration: 872.643418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:15.801041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:14.927887Z","time spent":"873.11442ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T19:33:39.234578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063968082,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T19:33:39.472283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054905447s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T19:33:39.472689Z","caller":"traceutil/trace.go:171","msg":"trace[1605899124] linearizableReadLoop","detail":"{readStateIndex:6149; appliedIndex:6148; }","duration":"738.587063ms","start":"2026-07-30T19:33:38.734083Z","end":"2026-07-30T19:33:39.47267Z","steps":["trace[1605899124] 'read index received' (duration: 738.515671ms)","trace[1605899124] 'applied index is now lower than readState.Index' (duration: 70.842µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:33:39.472809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.717726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:33:39.472829Z","caller":"traceutil/trace.go:171","msg":"trace[494671850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5945; }","duration":"738.794328ms","start":"2026-07-30T19:33:38.734028Z","end":"2026-07-30T19:33:39.472822Z","steps":["trace[494671850] 'agreement among raft nodes before linearized reading' (duration: 738.742186ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:33:39.472807Z","caller":"traceutil/trace.go:171","msg":"trace[1003870068] transaction","detail":"{read_only:false; response_revision:5945; number_of_response:1; }","duration":"1.055592437s","start":"2026-07-30T19:33:38.417185Z","end":"2026-07-30T19:33:39.472778Z","steps":["trace[1003870068] 'process raft request' (duration: 1.055301789s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:39.472847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:38.734001Z","time spent":"738.842409ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:33:39.472932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:38.417152Z","time spent":"1.05571267s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:33:39.472976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.682873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-30T19:33:39.473004Z","caller":"traceutil/trace.go:171","msg":"trace[677129186] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:5945; }","duration":"505.751645ms","start":"2026-07-30T19:33:38.967245Z","end":"2026-07-30T19:33:39.472997Z","steps":["trace[677129186] 'agreement among raft nodes before linearized reading' (duration: 505.658543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:39.473022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:38.967221Z","time spent":"505.796498ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-30T19:33:39.473107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.219015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:33:39.47316Z","caller":"traceutil/trace.go:171","msg":"trace[1181744650] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5945; }","duration":"214.324278ms","start":"2026-07-30T19:33:39.258818Z","end":"2026-07-30T19:33:39.473142Z","steps":["trace[1181744650] 'agreement among raft nodes before linearized reading' (duration: 214.202375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.475078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063968165,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:33:44.507685Z","caller":"traceutil/trace.go:171","msg":"trace[1535780826] transaction","detail":"{read_only:false; response_revision:5974; number_of_response:1; }","duration":"546.544065ms","start":"2026-07-30T19:33:43.961111Z","end":"2026-07-30T19:33:44.507655Z","steps":["trace[1535780826] 'process raft request' (duration: 546.3641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.507867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:43.961095Z","time spent":"546.686248ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:33:44.509541Z","caller":"traceutil/trace.go:171","msg":"trace[1176454496] transaction","detail":"{read_only:false; response_revision:5976; number_of_response:1; }","duration":"454.284234ms","start":"2026-07-30T19:33:44.055226Z","end":"2026-07-30T19:33:44.50951Z","steps":["trace[1176454496] 'process raft request' (duration: 454.14616ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:33:44.509568Z","caller":"traceutil/trace.go:171","msg":"trace[519934925] transaction","detail":"{read_only:false; response_revision:5975; number_of_response:1; }","duration":"513.007867ms","start":"2026-07-30T19:33:43.996495Z","end":"2026-07-30T19:33:44.509503Z","steps":["trace[519934925] 'process raft request' (duration: 512.730439ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:33:44.509731Z","caller":"traceutil/trace.go:171","msg":"trace[1494859066] transaction","detail":"{read_only:false; response_revision:5977; number_of_response:1; }","duration":"448.07185ms","start":"2026-07-30T19:33:44.061616Z","end":"2026-07-30T19:33:44.509688Z","steps":["trace[1494859066] 'process raft request' (duration: 447.804152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.509838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:43.996485Z","time spent":"513.196502ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:33:44.509867Z","caller":"traceutil/trace.go:171","msg":"trace[249914936] transaction","detail":"{read_only:false; response_revision:5978; number_of_response:1; }","duration":"447.729141ms","start":"2026-07-30T19:33:44.062055Z","end":"2026-07-30T19:33:44.509784Z","steps":["trace[249914936] 'process raft request' (duration: 447.406242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.50993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:44.061611Z","time spent":"448.197103ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:33:44.51016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:44.06205Z","time spent":"447.974468ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:33:44.510203Z","caller":"traceutil/trace.go:171","msg":"trace[1076650429] linearizableReadLoop","detail":"{readStateIndex:6183; appliedIndex:6178; }","duration":"535.229805ms","start":"2026-07-30T19:33:43.974407Z","end":"2026-07-30T19:33:44.509637Z","steps":["trace[1076650429] 'read index received' (duration: 533.086909ms)","trace[1076650429] 'applied index is now lower than readState.Index' (duration: 2.141276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:33:44.510297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:44.055216Z","time spent":"454.412037ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:33:44.510298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.821211ms","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-07-30T19:33:44.510415Z","caller":"traceutil/trace.go:171","msg":"trace[764406013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5978; }","duration":"536.013506ms","start":"2026-07-30T19:33:43.974374Z","end":"2026-07-30T19:33:44.510388Z","steps":["trace[764406013] 'agreement among raft nodes before linearized reading' (duration: 535.542033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.510464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:43.974359Z","time spent":"536.094358ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T19:33:44.51048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.981985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-30T19:33:44.510953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.436938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-30T19:33:44.511033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.49934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T19:33:44.511248Z","caller":"traceutil/trace.go:171","msg":"trace[943116163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5978; }","duration":"536.718195ms","start":"2026-07-30T19:33:43.974504Z","end":"2026-07-30T19:33:44.511222Z","steps":["trace[943116163] 'agreement among raft nodes before linearized reading' (duration: 536.109409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.511745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:43.974501Z","time spent":"537.225029ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T19:33:44.511018Z","caller":"traceutil/trace.go:171","msg":"trace[1110316457] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:5978; }","duration":"536.513299ms","start":"2026-07-30T19:33:43.974491Z","end":"2026-07-30T19:33:44.511004Z","steps":["trace[1110316457] 'agreement among raft nodes before linearized reading' (duration: 536.150269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.511958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:43.974488Z","time spent":"537.418193ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T19:33:44.510541Z","caller":"traceutil/trace.go:171","msg":"trace[2035871474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5978; }","duration":"536.045457ms","start":"2026-07-30T19:33:43.974473Z","end":"2026-07-30T19:33:44.510519Z","steps":["trace[2035871474] 'agreement among raft nodes before linearized reading' (duration: 535.851022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:33:44.512293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:33:43.97447Z","time spent":"537.804113ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T19:35:10.966892Z","caller":"traceutil/trace.go:171","msg":"trace[859543463] transaction","detail":"{read_only:false; response_revision:6692; number_of_response:1; }","duration":"160.420704ms","start":"2026-07-30T19:35:10.806451Z","end":"2026-07-30T19:35:10.966872Z","steps":["trace[859543463] 'process raft request' (duration: 160.194229ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:10.967129Z","caller":"traceutil/trace.go:171","msg":"trace[2052117170] transaction","detail":"{read_only:false; response_revision:6694; number_of_response:1; }","duration":"138.828503ms","start":"2026-07-30T19:35:10.828275Z","end":"2026-07-30T19:35:10.967104Z","steps":["trace[2052117170] 'process raft request' (duration: 138.71194ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:10.967167Z","caller":"traceutil/trace.go:171","msg":"trace[590492753] transaction","detail":"{read_only:false; response_revision:6693; number_of_response:1; }","duration":"139.633154ms","start":"2026-07-30T19:35:10.827514Z","end":"2026-07-30T19:35:10.967147Z","steps":["trace[590492753] 'process raft request' (duration: 139.307745ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:10.972323Z","caller":"traceutil/trace.go:171","msg":"trace[1923524237] transaction","detail":"{read_only:false; response_revision:6695; number_of_response:1; }","duration":"132.443303ms","start":"2026-07-30T19:35:10.83984Z","end":"2026-07-30T19:35:10.972283Z","steps":["trace[1923524237] 'process raft request' (duration: 132.100245ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:10.972337Z","caller":"traceutil/trace.go:171","msg":"trace[950474652] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"128.567572ms","start":"2026-07-30T19:35:10.84373Z","end":"2026-07-30T19:35:10.972298Z","steps":["trace[950474652] 'process raft request' (duration: 128.464359ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:10.972324Z","caller":"traceutil/trace.go:171","msg":"trace[2106889519] transaction","detail":"{read_only:false; response_revision:6696; number_of_response:1; }","duration":"128.688664ms","start":"2026-07-30T19:35:10.843596Z","end":"2026-07-30T19:35:10.972284Z","steps":["trace[2106889519] 'process raft request' (duration: 128.546371ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:11.10766Z","caller":"traceutil/trace.go:171","msg":"trace[1893483080] transaction","detail":"{read_only:false; response_revision:6699; number_of_response:1; }","duration":"130.242366ms","start":"2026-07-30T19:35:10.977396Z","end":"2026-07-30T19:35:11.107639Z","steps":["trace[1893483080] 'process raft request' (duration: 80.228613ms)","trace[1893483080] 'compare' (duration: 49.820798ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:35:11.107862Z","caller":"traceutil/trace.go:171","msg":"trace[514772488] transaction","detail":"{read_only:false; response_revision:6700; number_of_response:1; }","duration":"130.37321ms","start":"2026-07-30T19:35:10.977479Z","end":"2026-07-30T19:35:11.107852Z","steps":["trace[514772488] 'process raft request' (duration: 130.085942ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:11.108006Z","caller":"traceutil/trace.go:171","msg":"trace[1349263414] linearizableReadLoop","detail":"{readStateIndex:6929; appliedIndex:6925; }","duration":"130.230916ms","start":"2026-07-30T19:35:10.977767Z","end":"2026-07-30T19:35:11.107998Z","steps":["trace[1349263414] 'read index received' (duration: 18.595672ms)","trace[1349263414] 'applied index is now lower than readState.Index' (duration: 111.634654ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:35:11.108071Z","caller":"traceutil/trace.go:171","msg":"trace[250799552] transaction","detail":"{read_only:false; response_revision:6701; number_of_response:1; }","duration":"130.45309ms","start":"2026-07-30T19:35:10.977609Z","end":"2026-07-30T19:35:11.108063Z","steps":["trace[250799552] 'process raft request' (duration: 130.213984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:35:11.108282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.498282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-30T19:35:11.108353Z","caller":"traceutil/trace.go:171","msg":"trace[1246772331] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:6703; }","duration":"130.598314ms","start":"2026-07-30T19:35:10.977737Z","end":"2026-07-30T19:35:11.108335Z","steps":["trace[1246772331] 'agreement among raft nodes before linearized reading' (duration: 130.44195ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:11.108496Z","caller":"traceutil/trace.go:171","msg":"trace[224988577] transaction","detail":"{read_only:false; response_revision:6702; number_of_response:1; }","duration":"130.245726ms","start":"2026-07-30T19:35:10.978241Z","end":"2026-07-30T19:35:11.108487Z","steps":["trace[224988577] 'process raft request' (duration: 129.697721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:35:11.108304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.481237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:35:11.108563Z","caller":"traceutil/trace.go:171","msg":"trace[571824182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:6703; }","duration":"106.810446ms","start":"2026-07-30T19:35:11.001743Z","end":"2026-07-30T19:35:11.108553Z","steps":["trace[571824182] 'agreement among raft nodes before linearized reading' (duration: 106.514737ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:11.108635Z","caller":"traceutil/trace.go:171","msg":"trace[71181722] transaction","detail":"{read_only:false; response_revision:6703; number_of_response:1; }","duration":"130.305327ms","start":"2026-07-30T19:35:10.978322Z","end":"2026-07-30T19:35:11.108627Z","steps":["trace[71181722] 'process raft request' (duration: 129.64334ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:57.447494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4926} {"level":"info","ts":"2026-07-30T19:35:57.486439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4926,"took":"37.711877ms","hash":2258009760,"current-db-size-bytes":34357248,"current-db-size":"34 MB","current-db-size-in-use-bytes":10809344,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-30T19:35:57.486522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2258009760,"revision":4926,"compact-revision":2939} {"level":"info","ts":"2026-07-30T19:36:09.458504Z","caller":"traceutil/trace.go:171","msg":"trace[813426764] transaction","detail":"{read_only:false; response_revision:7215; number_of_response:1; }","duration":"145.5871ms","start":"2026-07-30T19:36:09.312875Z","end":"2026-07-30T19:36:09.458462Z","steps":["trace[813426764] 'process raft request' (duration: 145.270582ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:38:49.91482Z","caller":"traceutil/trace.go:171","msg":"trace[27731357] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"102.561055ms","start":"2026-07-30T19:38:49.812223Z","end":"2026-07-30T19:38:49.914784Z","steps":["trace[27731357] 'process raft request' (duration: 102.348238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:38:55.027725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.316141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:38:55.027868Z","caller":"traceutil/trace.go:171","msg":"trace[688492059] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8458; }","duration":"118.527966ms","start":"2026-07-30T19:38:54.909311Z","end":"2026-07-30T19:38:55.027839Z","steps":["trace[688492059] 'count revisions from in-memory index tree' (duration: 118.222739ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:40:57.454372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7119} {"level":"info","ts":"2026-07-30T19:40:57.507762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7119,"took":"52.316275ms","hash":3339907393,"current-db-size-bytes":34357248,"current-db-size":"34 MB","current-db-size-in-use-bytes":10977280,"current-db-size-in-use":"11 MB"} {"level":"info","ts":"2026-07-30T19:40:57.507822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339907393,"revision":7119,"compact-revision":4926} {"level":"warn","ts":"2026-07-30T19:41:38.144781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063976328,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:41:38.211478Z","caller":"traceutil/trace.go:171","msg":"trace[914587230] linearizableReadLoop","detail":"{readStateIndex:9806; appliedIndex:9805; }","duration":"567.394958ms","start":"2026-07-30T19:41:37.644062Z","end":"2026-07-30T19:41:38.211457Z","steps":["trace[914587230] 'read index received' (duration: 567.148272ms)","trace[914587230] 'applied index is now lower than readState.Index' (duration: 245.806µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:41:38.211561Z","caller":"traceutil/trace.go:171","msg":"trace[1300572641] transaction","detail":"{read_only:false; response_revision:9492; number_of_response:1; }","duration":"662.299397ms","start":"2026-07-30T19:41:37.549212Z","end":"2026-07-30T19:41:38.211511Z","steps":["trace[1300572641] 'process raft request' (duration: 662.100372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:38.211634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.757686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:41:38.211662Z","caller":"traceutil/trace.go:171","msg":"trace[1292142648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9492; }","duration":"478.840648ms","start":"2026-07-30T19:41:37.732811Z","end":"2026-07-30T19:41:38.211652Z","steps":["trace[1292142648] 'agreement among raft nodes before linearized reading' (duration: 478.775886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:38.211694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:37.732791Z","time spent":"478.894819ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:41:38.211723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:37.549171Z","time spent":"662.467811ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:41:38.211766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.675265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12462"} {"level":"info","ts":"2026-07-30T19:41:38.2124Z","caller":"traceutil/trace.go:171","msg":"trace[9494378] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9492; }","duration":"568.370974ms","start":"2026-07-30T19:41:37.644Z","end":"2026-07-30T19:41:38.21237Z","steps":["trace[9494378] 'agreement among raft nodes before linearized reading' (duration: 567.550702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:38.212471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:37.643972Z","time spent":"568.479578ms","remote":"127.0.0.1:56570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12486,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-30T19:41:39.335779Z","caller":"traceutil/trace.go:171","msg":"trace[369874744] transaction","detail":"{read_only:false; response_revision:9497; number_of_response:1; }","duration":"219.082621ms","start":"2026-07-30T19:41:39.116666Z","end":"2026-07-30T19:41:39.335749Z","steps":["trace[369874744] 'process raft request' (duration: 218.935038ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:39.335798Z","caller":"traceutil/trace.go:171","msg":"trace[889966518] linearizableReadLoop","detail":"{readStateIndex:9811; appliedIndex:9811; }","duration":"216.953735ms","start":"2026-07-30T19:41:39.118817Z","end":"2026-07-30T19:41:39.33577Z","steps":["trace[889966518] 'read index received' (duration: 216.925984ms)","trace[889966518] 'applied index is now lower than readState.Index' (duration: 25.081µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:41:39.336106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.258563ms","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-07-30T19:41:39.33621Z","caller":"traceutil/trace.go:171","msg":"trace[1177020352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9497; }","duration":"217.417027ms","start":"2026-07-30T19:41:39.118772Z","end":"2026-07-30T19:41:39.336189Z","steps":["trace[1177020352] 'agreement among raft nodes before linearized reading' (duration: 217.047577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:39.337443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.998743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T19:41:39.33756Z","caller":"traceutil/trace.go:171","msg":"trace[968586192] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:9499; }","duration":"145.160578ms","start":"2026-07-30T19:41:39.192377Z","end":"2026-07-30T19:41:39.337537Z","steps":["trace[968586192] 'agreement among raft nodes before linearized reading' (duration: 144.90036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:39.337765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.812238ms","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-07-30T19:41:39.337794Z","caller":"traceutil/trace.go:171","msg":"trace[2074272679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9499; }","duration":"144.88409ms","start":"2026-07-30T19:41:39.192902Z","end":"2026-07-30T19:41:39.337786Z","steps":["trace[2074272679] 'agreement among raft nodes before linearized reading' (duration: 144.813158ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:39.337912Z","caller":"traceutil/trace.go:171","msg":"trace[1739336634] transaction","detail":"{read_only:false; response_revision:9498; number_of_response:1; }","duration":"218.514956ms","start":"2026-07-30T19:41:39.119376Z","end":"2026-07-30T19:41:39.337891Z","steps":["trace[1739336634] 'process raft request' (duration: 217.722615ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:39.338257Z","caller":"traceutil/trace.go:171","msg":"trace[1910571015] transaction","detail":"{read_only:false; response_revision:9499; number_of_response:1; }","duration":"217.880899ms","start":"2026-07-30T19:41:39.119777Z","end":"2026-07-30T19:41:39.337658Z","steps":["trace[1910571015] 'process raft request' (duration: 217.455847ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:49.205803Z","caller":"traceutil/trace.go:171","msg":"trace[489280436] transaction","detail":"{read_only:false; response_revision:9559; number_of_response:1; }","duration":"678.69135ms","start":"2026-07-30T19:41:48.527061Z","end":"2026-07-30T19:41:49.205752Z","steps":["trace[489280436] 'process raft request' (duration: 678.275989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:49.206371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:48.527038Z","time spent":"679.206875ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:41:49.206945Z","caller":"traceutil/trace.go:171","msg":"trace[749720641] transaction","detail":"{read_only:false; response_revision:9560; number_of_response:1; }","duration":"662.543625ms","start":"2026-07-30T19:41:48.544373Z","end":"2026-07-30T19:41:49.206917Z","steps":["trace[749720641] 'process raft request' (duration: 662.269347ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:49.206999Z","caller":"traceutil/trace.go:171","msg":"trace[1023316511] linearizableReadLoop","detail":"{readStateIndex:9878; appliedIndex:9874; }","duration":"484.292412ms","start":"2026-07-30T19:41:48.722649Z","end":"2026-07-30T19:41:49.206942Z","steps":["trace[1023316511] 'read index received' (duration: 482.900425ms)","trace[1023316511] 'applied index is now lower than readState.Index' (duration: 1.390677ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:41:49.20724Z","caller":"traceutil/trace.go:171","msg":"trace[1076534078] transaction","detail":"{read_only:false; response_revision:9561; number_of_response:1; }","duration":"661.879326ms","start":"2026-07-30T19:41:48.545333Z","end":"2026-07-30T19:41:49.207212Z","steps":["trace[1076534078] 'process raft request' (duration: 661.508277ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:49.207277Z","caller":"traceutil/trace.go:171","msg":"trace[605677397] transaction","detail":"{read_only:false; response_revision:9562; number_of_response:1; }","duration":"660.625653ms","start":"2026-07-30T19:41:48.546641Z","end":"2026-07-30T19:41:49.207266Z","steps":["trace[605677397] 'process raft request' (duration: 660.246764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:49.207317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:48.545324Z","time spent":"661.954569ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:41:49.207344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:48.544348Z","time spent":"662.650768ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:41:49.207387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:48.546633Z","time spent":"660.670704ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:41:49.207513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.096773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:41:49.207529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.877328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:41:49.207567Z","caller":"traceutil/trace.go:171","msg":"trace[1130539863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9562; }","duration":"474.171095ms","start":"2026-07-30T19:41:48.733381Z","end":"2026-07-30T19:41:49.207552Z","steps":["trace[1130539863] 'agreement among raft nodes before linearized reading' (duration: 474.044111ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:41:49.207583Z","caller":"traceutil/trace.go:171","msg":"trace[405377486] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:9562; }","duration":"484.974781ms","start":"2026-07-30T19:41:48.722595Z","end":"2026-07-30T19:41:49.20757Z","steps":["trace[405377486] 'agreement among raft nodes before linearized reading' (duration: 484.883899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:41:49.207596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:48.733365Z","time spent":"474.224986ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T19:41:49.207613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:41:48.722572Z","time spent":"485.033282ms","remote":"127.0.0.1:60066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:42:07.503721Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T19:42:07.508827Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T19:42:07.509154Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-07-30T19:42:13.928219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976534091063976850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T19:42:13.984496Z","caller":"traceutil/trace.go:171","msg":"trace[572349216] transaction","detail":"{read_only:false; response_revision:9713; number_of_response:1; }","duration":"698.941486ms","start":"2026-07-30T19:42:13.28553Z","end":"2026-07-30T19:42:13.984472Z","steps":["trace[572349216] 'process raft request' (duration: 698.750551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.984651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.285507Z","time spent":"699.061401ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:42:13.985449Z","caller":"traceutil/trace.go:171","msg":"trace[1482909123] transaction","detail":"{read_only:false; response_revision:9714; number_of_response:1; }","duration":"681.30286ms","start":"2026-07-30T19:42:13.304131Z","end":"2026-07-30T19:42:13.985434Z","steps":["trace[1482909123] 'process raft request' (duration: 681.208148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.985524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.304105Z","time spent":"681.380803ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:42:13.985775Z","caller":"traceutil/trace.go:171","msg":"trace[2119211531] transaction","detail":"{read_only:false; response_revision:9715; number_of_response:1; }","duration":"680.465418ms","start":"2026-07-30T19:42:13.305275Z","end":"2026-07-30T19:42:13.985741Z","steps":["trace[2119211531] 'process raft request' (duration: 680.127139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.985917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.652498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T19:42:13.985884Z","caller":"traceutil/trace.go:171","msg":"trace[1891240547] transaction","detail":"{read_only:false; response_revision:9716; number_of_response:1; }","duration":"678.694251ms","start":"2026-07-30T19:42:13.307155Z","end":"2026-07-30T19:42:13.985849Z","steps":["trace[1891240547] 'process raft request' (duration: 678.465795ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:42:13.985973Z","caller":"traceutil/trace.go:171","msg":"trace[1568273764] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:9717; }","duration":"681.751322ms","start":"2026-07-30T19:42:13.304208Z","end":"2026-07-30T19:42:13.985959Z","steps":["trace[1568273764] 'agreement among raft nodes before linearized reading' (duration: 681.640169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.986018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.304197Z","time spent":"681.810234ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":14,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:42:13.985956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.30526Z","time spent":"680.603882ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T19:42:13.986078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.307119Z","time spent":"678.865036ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T19:42:13.985798Z","caller":"traceutil/trace.go:171","msg":"trace[387458011] linearizableReadLoop","detail":"{readStateIndex:10037; appliedIndex:10033; }","duration":"681.518136ms","start":"2026-07-30T19:42:13.304251Z","end":"2026-07-30T19:42:13.985769Z","steps":["trace[387458011] 'read index received' (duration: 680.059337ms)","trace[387458011] 'applied index is now lower than readState.Index' (duration: 1.457569ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:42:13.986224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.651707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T19:42:13.986271Z","caller":"traceutil/trace.go:171","msg":"trace[707218144] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:9717; }","duration":"679.718039ms","start":"2026-07-30T19:42:13.306541Z","end":"2026-07-30T19:42:13.986259Z","steps":["trace[707218144] 'agreement among raft nodes before linearized reading' (duration: 679.645687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.986299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.306533Z","time spent":"679.75778ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-30T19:42:13.986608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.695567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-30T19:42:13.986648Z","caller":"traceutil/trace.go:171","msg":"trace[2012996871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9717; }","duration":"452.759779ms","start":"2026-07-30T19:42:13.533879Z","end":"2026-07-30T19:42:13.986639Z","steps":["trace[2012996871] 'agreement among raft nodes before linearized reading' (duration: 452.564093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.986676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.533867Z","time spent":"452.80243ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T19:42:13.986762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.663409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T19:42:13.986796Z","caller":"traceutil/trace.go:171","msg":"trace[1347067323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9717; }","duration":"457.763811ms","start":"2026-07-30T19:42:13.529023Z","end":"2026-07-30T19:42:13.986787Z","steps":["trace[1347067323] 'agreement among raft nodes before linearized reading' (duration: 457.640278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.986827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.528998Z","time spent":"457.818933ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T19:42:13.986979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.368091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T19:42:13.987005Z","caller":"traceutil/trace.go:171","msg":"trace[1299455175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9717; }","duration":"454.417102ms","start":"2026-07-30T19:42:13.532579Z","end":"2026-07-30T19:42:13.986996Z","steps":["trace[1299455175] 'agreement among raft nodes before linearized reading' (duration: 454.351731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.98703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.532563Z","time spent":"454.459853ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T19:42:13.986829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.620791ms","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-07-30T19:42:13.987087Z","caller":"traceutil/trace.go:171","msg":"trace[58228884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9717; }","duration":"459.922568ms","start":"2026-07-30T19:42:13.527146Z","end":"2026-07-30T19:42:13.987068Z","steps":["trace[58228884] 'agreement among raft nodes before linearized reading' (duration: 459.59548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.987136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.328639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-30T19:42:13.98716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.527126Z","time spent":"460.01387ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T19:42:13.987171Z","caller":"traceutil/trace.go:171","msg":"trace[998925676] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:9717; }","duration":"457.38691ms","start":"2026-07-30T19:42:13.529774Z","end":"2026-07-30T19:42:13.987161Z","steps":["trace[998925676] 'agreement among raft nodes before linearized reading' (duration: 457.265217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:42:13.98727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:42:13.529762Z","time spent":"457.494613ms","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T19:45:57.462557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9238} {"level":"info","ts":"2026-07-30T19:45:57.51654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9238,"took":"52.646863ms","hash":1723844738,"current-db-size-bytes":34357248,"current-db-size":"34 MB","current-db-size-in-use-bytes":9109504,"current-db-size-in-use":"9.1 MB"} {"level":"info","ts":"2026-07-30T19:45:57.516616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1723844738,"revision":9238,"compact-revision":7119}