{"level":"warn","ts":"2026-04-16T22:42:48.318865Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:42:48.318946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T22:42:48.319007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:42:48.319021Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-16T22:42:48.319038Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:42:48.319596Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-04-16T22:42:48.319664Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T22:42:48.327288Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.107791ms"} {"level":"info","ts":"2026-04-16T22:42:48.337134Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-04-16T22:42:48.337205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:42:48.337229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-04-16T22:42:48.337242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:42:48.337254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-04-16T22:42:48.337293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-04-16T22:42:48.346414Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:42:48.353253Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:42:48.35437Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T22:42:48.358423Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:42:48.358841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:42:48.358961Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:42:48.359005Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T22:42:48.358984Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:42:48.361199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-04-16T22:42:48.361605Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T22:42:48.363288Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-16T22:42:48.363662Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:42:48.363747Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:42:48.361649Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-16T22:42:48.364043Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-16T22:42:48.838017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:42:48.838081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:42:48.838106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-04-16T22:42:48.838143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:42:48.838154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-16T22:42:48.83817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-04-16T22:42:48.838184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-16T22:42:48.839271Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:42:48.839499Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:48.839596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:42:48.840236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:42:48.840422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:48.840534Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:48.840564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:42:48.840315Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:42:48.840592Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:42:48.843579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T22:42:48.843587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"warn","ts":"2026-04-16T22:43:17.299588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.999185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-16T22:43:17.299709Z","caller":"traceutil/trace.go:171","msg":"trace[236817214] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:504; }","duration":"190.24381ms","start":"2026-04-16T22:43:17.109441Z","end":"2026-04-16T22:43:17.299685Z","steps":["trace[236817214] 'range keys from in-memory index tree' (duration: 189.875959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:17.29971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.968704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"warn","ts":"2026-04-16T22:43:17.299735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.679736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-16T22:43:17.299782Z","caller":"traceutil/trace.go:171","msg":"trace[476163301] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:504; }","duration":"211.103631ms","start":"2026-04-16T22:43:17.088658Z","end":"2026-04-16T22:43:17.299761Z","steps":["trace[476163301] 'range keys from in-memory index tree' (duration: 210.640485ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:17.299824Z","caller":"traceutil/trace.go:171","msg":"trace[1830264170] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:504; }","duration":"190.856106ms","start":"2026-04-16T22:43:17.108946Z","end":"2026-04-16T22:43:17.299802Z","steps":["trace[1830264170] 'range keys from in-memory index tree' (duration: 190.467443ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.975272Z","caller":"traceutil/trace.go:171","msg":"trace[84875354] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"223.960599ms","start":"2026-04-16T22:43:18.751287Z","end":"2026-04-16T22:43:18.975248Z","steps":["trace[84875354] 'process raft request' (duration: 223.757897ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.996733Z","caller":"traceutil/trace.go:171","msg":"trace[328381963] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"159.659285ms","start":"2026-04-16T22:43:18.837061Z","end":"2026-04-16T22:43:18.99672Z","steps":["trace[328381963] 'process raft request' (duration: 159.439733ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.99669Z","caller":"traceutil/trace.go:171","msg":"trace[579502483] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"159.750011ms","start":"2026-04-16T22:43:18.836916Z","end":"2026-04-16T22:43:18.996666Z","steps":["trace[579502483] 'process raft request' (duration: 159.422293ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.996826Z","caller":"traceutil/trace.go:171","msg":"trace[304000619] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"151.576653ms","start":"2026-04-16T22:43:18.845241Z","end":"2026-04-16T22:43:18.996818Z","steps":["trace[304000619] 'process raft request' (duration: 151.439355ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.996957Z","caller":"traceutil/trace.go:171","msg":"trace[959177301] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"149.288431ms","start":"2026-04-16T22:43:18.847626Z","end":"2026-04-16T22:43:18.996914Z","steps":["trace[959177301] 'process raft request' (duration: 149.09728ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.996987Z","caller":"traceutil/trace.go:171","msg":"trace[711114823] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"159.664495ms","start":"2026-04-16T22:43:18.837094Z","end":"2026-04-16T22:43:18.996758Z","steps":["trace[711114823] 'process raft request' (duration: 159.543519ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.997326Z","caller":"traceutil/trace.go:171","msg":"trace[1681606601] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"146.80956ms","start":"2026-04-16T22:43:18.850503Z","end":"2026-04-16T22:43:18.997313Z","steps":["trace[1681606601] 'process raft request' (duration: 146.30449ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.997398Z","caller":"traceutil/trace.go:171","msg":"trace[1010644982] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"144.295256ms","start":"2026-04-16T22:43:18.853096Z","end":"2026-04-16T22:43:18.997392Z","steps":["trace[1010644982] 'process raft request' (duration: 144.151106ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.997528Z","caller":"traceutil/trace.go:171","msg":"trace[974039507] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"143.519301ms","start":"2026-04-16T22:43:18.85399Z","end":"2026-04-16T22:43:18.997509Z","steps":["trace[974039507] 'process raft request' (duration: 143.299698ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.997788Z","caller":"traceutil/trace.go:171","msg":"trace[1614082828] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"137.910551ms","start":"2026-04-16T22:43:18.859869Z","end":"2026-04-16T22:43:18.997779Z","steps":["trace[1614082828] 'process raft request' (duration: 137.507378ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.997962Z","caller":"traceutil/trace.go:171","msg":"trace[834166351] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"122.961603ms","start":"2026-04-16T22:43:18.87499Z","end":"2026-04-16T22:43:18.997952Z","steps":["trace[834166351] 'process raft request' (duration: 122.880429ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:18.998025Z","caller":"traceutil/trace.go:171","msg":"trace[816002300] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"132.33454ms","start":"2026-04-16T22:43:18.865684Z","end":"2026-04-16T22:43:18.998018Z","steps":["trace[816002300] 'process raft request' (duration: 132.094957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:19.941176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.850139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:19.941301Z","caller":"traceutil/trace.go:171","msg":"trace[1412928042] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"298.001087ms","start":"2026-04-16T22:43:19.643281Z","end":"2026-04-16T22:43:19.941282Z","steps":["trace[1412928042] 'process raft request' (duration: 98.461619ms)","trace[1412928042] 'compare' (duration: 198.616785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:20.464236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.063756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:20.464321Z","caller":"traceutil/trace.go:171","msg":"trace[782049021] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"810.722638ms","start":"2026-04-16T22:43:19.653585Z","end":"2026-04-16T22:43:20.464308Z","steps":["trace[782049021] 'process raft request' (duration: 602.510924ms)","trace[782049021] 'compare' (duration: 207.845183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:20.464358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:19.653508Z","time spent":"810.834174ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:43:20.48863Z","caller":"traceutil/trace.go:171","msg":"trace[287136198] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"225.737056ms","start":"2026-04-16T22:43:20.262842Z","end":"2026-04-16T22:43:20.488579Z","steps":["trace[287136198] 'read index received' (duration: 224.677974ms)","trace[287136198] 'applied index is now lower than readState.Index' (duration: 1.057812ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:20.488784Z","caller":"traceutil/trace.go:171","msg":"trace[389972294] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"688.422074ms","start":"2026-04-16T22:43:19.800324Z","end":"2026-04-16T22:43:20.488746Z","steps":["trace[389972294] 'process raft request' (duration: 687.697043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:20.488842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.981089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:43:20.488872Z","caller":"traceutil/trace.go:171","msg":"trace[745622414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"226.059024ms","start":"2026-04-16T22:43:20.262804Z","end":"2026-04-16T22:43:20.488863Z","steps":["trace[745622414] 'agreement among raft nodes before linearized reading' (duration: 225.938907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:20.488953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:19.800301Z","time spent":"688.568613ms","remote":"127.0.0.1:47286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:43:22.397708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.080078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:43:22.397809Z","caller":"traceutil/trace.go:171","msg":"trace[1826575052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"129.245988ms","start":"2026-04-16T22:43:22.268541Z","end":"2026-04-16T22:43:22.397787Z","steps":["trace[1826575052] 'range keys from in-memory index tree' (duration: 128.95675ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:26.228402Z","caller":"traceutil/trace.go:171","msg":"trace[803098582] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"261.007513ms","start":"2026-04-16T22:43:25.967365Z","end":"2026-04-16T22:43:26.228373Z","steps":["trace[803098582] 'read index received' (duration: 259.699726ms)","trace[803098582] 'applied index is now lower than readState.Index' (duration: 1.306887ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:26.228462Z","caller":"traceutil/trace.go:171","msg":"trace[1053999295] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"640.272379ms","start":"2026-04-16T22:43:25.58814Z","end":"2026-04-16T22:43:26.228413Z","steps":["trace[1053999295] 'process raft request' (duration: 639.291821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:26.22858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.196954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gd65f\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T22:43:26.228651Z","caller":"traceutil/trace.go:171","msg":"trace[2067771876] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gd65f; range_end:; response_count:1; response_revision:616; }","duration":"261.334592ms","start":"2026-04-16T22:43:25.967304Z","end":"2026-04-16T22:43:26.228638Z","steps":["trace[2067771876] 'agreement among raft nodes before linearized reading' (duration: 261.185733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:26.22873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:25.588092Z","time spent":"640.472611ms","remote":"127.0.0.1:47328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:43:26.759969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019792298710542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:43:27.476182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.419207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:43:27.476902Z","caller":"traceutil/trace.go:171","msg":"trace[948551355] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.184814726s","start":"2026-04-16T22:43:26.292059Z","end":"2026-04-16T22:43:27.476874Z","steps":["trace[948551355] 'process raft request' (duration: 1.184238552s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:27.477057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:26.292039Z","time spent":"1.184947204s","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:43:27.477114Z","caller":"traceutil/trace.go:171","msg":"trace[901226601] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"1.217798451s","start":"2026-04-16T22:43:26.259188Z","end":"2026-04-16T22:43:27.476986Z","steps":["trace[901226601] 'read index received' (duration: 685.478191ms)","trace[901226601] 'applied index is now lower than readState.Index' (duration: 532.316989ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:43:27.477368Z","caller":"traceutil/trace.go:171","msg":"trace[434196256] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.230437072s","start":"2026-04-16T22:43:26.246444Z","end":"2026-04-16T22:43:27.476881Z","steps":["trace[434196256] 'process raft request' (duration: 698.211769ms)","trace[434196256] 'compare' (duration: 530.851483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:27.477531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:26.246424Z","time spent":"1.230991166s","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:43:27.512684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.821674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-16T22:43:27.512702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.327688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T22:43:27.512732Z","caller":"traceutil/trace.go:171","msg":"trace[940682529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"378.907859ms","start":"2026-04-16T22:43:27.133814Z","end":"2026-04-16T22:43:27.512721Z","steps":["trace[940682529] 'agreement among raft nodes before linearized reading' (duration: 378.74365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:27.512762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:27.13379Z","time spent":"378.966682ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T22:43:27.512764Z","caller":"traceutil/trace.go:171","msg":"trace[2082585763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"491.437245ms","start":"2026-04-16T22:43:27.021309Z","end":"2026-04-16T22:43:27.512747Z","steps":["trace[2082585763] 'agreement among raft nodes before linearized reading' (duration: 491.232873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:27.512815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:27.021286Z","time spent":"491.520869ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:43:27.512881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.690469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:43:27.512937Z","caller":"traceutil/trace.go:171","msg":"trace[1181657147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"973.786915ms","start":"2026-04-16T22:43:26.539138Z","end":"2026-04-16T22:43:27.512924Z","steps":["trace[1181657147] 'agreement among raft nodes before linearized reading' (duration: 973.635536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:27.512977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:26.539118Z","time spent":"973.848149ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:43:27.512715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.516792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T22:43:27.513194Z","caller":"traceutil/trace.go:171","msg":"trace[1226305920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"432.070565ms","start":"2026-04-16T22:43:27.081105Z","end":"2026-04-16T22:43:27.513176Z","steps":["trace[1226305920] 'agreement among raft nodes before linearized reading' (duration: 431.413876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:27.51325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:27.081081Z","time spent":"432.15856ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T22:43:27.513127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253938875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:43:27.513394Z","caller":"traceutil/trace.go:171","msg":"trace[626153683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.254237034s","start":"2026-04-16T22:43:26.259139Z","end":"2026-04-16T22:43:27.513376Z","steps":["trace[626153683] 'agreement among raft nodes before linearized reading' (duration: 1.217980971s)","trace[626153683] 'range keys from in-memory index tree' (duration: 35.973416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:43:27.513455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:26.259118Z","time spent":"1.254325608s","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:43:29.296935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.717859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:43:29.29705Z","caller":"traceutil/trace.go:171","msg":"trace[109698864] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:657; }","duration":"112.871248ms","start":"2026-04-16T22:43:29.184149Z","end":"2026-04-16T22:43:29.297021Z","steps":["trace[109698864] 'range keys from in-memory index tree' (duration: 112.593471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:29.297234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.210328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:43:29.297322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.149544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:43:29.297385Z","caller":"traceutil/trace.go:171","msg":"trace[685040596] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:657; }","duration":"113.430931ms","start":"2026-04-16T22:43:29.183931Z","end":"2026-04-16T22:43:29.297362Z","steps":["trace[685040596] 'range keys from in-memory index tree' (duration: 112.952622ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:29.297456Z","caller":"traceutil/trace.go:171","msg":"trace[1101262387] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:657; }","duration":"113.25646ms","start":"2026-04-16T22:43:29.184134Z","end":"2026-04-16T22:43:29.297391Z","steps":["trace[1101262387] 'count revisions from in-memory index tree' (duration: 112.992135ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:43:43.266837Z","caller":"traceutil/trace.go:171","msg":"trace[21113484] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"301.207513ms","start":"2026-04-16T22:43:42.96559Z","end":"2026-04-16T22:43:43.266797Z","steps":["trace[21113484] 'process raft request' (duration: 300.959071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:43:43.267067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:43:42.96556Z","time spent":"301.39119ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:43:43.27317Z","caller":"traceutil/trace.go:171","msg":"trace[2089082852] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"175.631207ms","start":"2026-04-16T22:43:43.097512Z","end":"2026-04-16T22:43:43.273143Z","steps":["trace[2089082852] 'process raft request' (duration: 175.339726ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:44:12.40689Z","caller":"traceutil/trace.go:171","msg":"trace[1335398021] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"142.926317ms","start":"2026-04-16T22:44:12.263933Z","end":"2026-04-16T22:44:12.406859Z","steps":["trace[1335398021] 'read index received' (duration: 142.824887ms)","trace[1335398021] 'applied index is now lower than readState.Index' (duration: 99.67µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:44:12.407044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.098047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:44:12.407076Z","caller":"traceutil/trace.go:171","msg":"trace[2102512427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"143.210386ms","start":"2026-04-16T22:44:12.263855Z","end":"2026-04-16T22:44:12.407066Z","steps":["trace[2102512427] 'agreement among raft nodes before linearized reading' (duration: 143.121667ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:44:12.833013Z","caller":"traceutil/trace.go:171","msg":"trace[2065751478] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"497.269497ms","start":"2026-04-16T22:44:12.335707Z","end":"2026-04-16T22:44:12.832977Z","steps":["trace[2065751478] 'process raft request' (duration: 496.760478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:44:12.833229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:44:12.335681Z","time spent":"497.434397ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:44:12.833286Z","caller":"traceutil/trace.go:171","msg":"trace[169127480] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1223; }","duration":"425.013265ms","start":"2026-04-16T22:44:12.408194Z","end":"2026-04-16T22:44:12.833208Z","steps":["trace[169127480] 'read index received' (duration: 424.214856ms)","trace[169127480] 'applied index is now lower than readState.Index' (duration: 789.089µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:44:12.833381Z","caller":"traceutil/trace.go:171","msg":"trace[32447003] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"474.025859ms","start":"2026-04-16T22:44:12.359323Z","end":"2026-04-16T22:44:12.833349Z","steps":["trace[32447003] 'process raft request' (duration: 473.51577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:44:12.833518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.291454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:44:12.833558Z","caller":"traceutil/trace.go:171","msg":"trace[117662999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"425.400894ms","start":"2026-04-16T22:44:12.408144Z","end":"2026-04-16T22:44:12.833545Z","steps":["trace[117662999] 'agreement among raft nodes before linearized reading' (duration: 425.246454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:44:12.8336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:44:12.408118Z","time spent":"425.474184ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:44:12.833558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:44:12.359303Z","time spent":"474.145379ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:44:12.870524Z","caller":"traceutil/trace.go:171","msg":"trace[433422163] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"462.045081ms","start":"2026-04-16T22:44:12.408449Z","end":"2026-04-16T22:44:12.870494Z","steps":["trace[433422163] 'process raft request' (duration: 461.865961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:44:12.873813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:44:12.408432Z","time spent":"462.18395ms","remote":"127.0.0.1:47260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:44:26.04413Z","caller":"traceutil/trace.go:171","msg":"trace[950390294] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"116.983912ms","start":"2026-04-16T22:44:25.927119Z","end":"2026-04-16T22:44:26.044103Z","steps":["trace[950390294] 'process raft request' (duration: 116.441037ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:44:27.201829Z","caller":"traceutil/trace.go:171","msg":"trace[1527937802] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"300.459696ms","start":"2026-04-16T22:44:26.901341Z","end":"2026-04-16T22:44:27.2018Z","steps":["trace[1527937802] 'process raft request' (duration: 297.991041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:44:27.202376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:44:26.90131Z","time spent":"300.670209ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:44:52.856954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.91204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:44:52.857017Z","caller":"traceutil/trace.go:171","msg":"trace[143311108] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1746; }","duration":"154.033093ms","start":"2026-04-16T22:44:52.702971Z","end":"2026-04-16T22:44:52.857005Z","steps":["trace[143311108] 'count revisions from in-memory index tree' (duration: 153.842649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:00.760775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019792298713014,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T22:45:01.215246Z","caller":"traceutil/trace.go:171","msg":"trace[211444937] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"963.981967ms","start":"2026-04-16T22:45:00.251229Z","end":"2026-04-16T22:45:01.215211Z","steps":["trace[211444937] 'process raft request' (duration: 963.74808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.215465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.251189Z","time spent":"964.182752ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:01.330492Z","caller":"traceutil/trace.go:171","msg":"trace[412105312] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"1.076079751s","start":"2026-04-16T22:45:00.254379Z","end":"2026-04-16T22:45:01.330459Z","steps":["trace[412105312] 'process raft request' (duration: 1.043505571s)","trace[412105312] 'compare' (duration: 32.383755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:01.330664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.254352Z","time spent":"1.076218485s","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:01.331008Z","caller":"traceutil/trace.go:171","msg":"trace[799292915] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"917.43992ms","start":"2026-04-16T22:45:00.413542Z","end":"2026-04-16T22:45:01.330982Z","steps":["trace[799292915] 'process raft request' (duration: 916.867624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.331162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.413524Z","time spent":"917.553883ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:01.331458Z","caller":"traceutil/trace.go:171","msg":"trace[1330996139] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"761.260316ms","start":"2026-04-16T22:45:00.570179Z","end":"2026-04-16T22:45:01.331439Z","steps":["trace[1330996139] 'process raft request' (duration: 761.107432ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:01.33145Z","caller":"traceutil/trace.go:171","msg":"trace[364759362] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1926; }","duration":"1.071578938s","start":"2026-04-16T22:45:00.259846Z","end":"2026-04-16T22:45:01.331425Z","steps":["trace[364759362] 'read index received' (duration: 955.092491ms)","trace[364759362] 'applied index is now lower than readState.Index' (duration: 116.485327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:01.331549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.570152Z","time spent":"761.353649ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:01.332779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071104775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T22:45:01.332839Z","caller":"traceutil/trace.go:171","msg":"trace[959714348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1856; }","duration":"1.071212787s","start":"2026-04-16T22:45:00.261611Z","end":"2026-04-16T22:45:01.332824Z","steps":["trace[959714348] 'agreement among raft nodes before linearized reading' (duration: 1.071026872s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.332882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.261586Z","time spent":"1.07128813s","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:45:01.332894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.935609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T22:45:01.332968Z","caller":"traceutil/trace.go:171","msg":"trace[1398735733] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb; range_end:; response_count:1; response_revision:1856; }","duration":"797.040082ms","start":"2026-04-16T22:45:00.5359Z","end":"2026-04-16T22:45:01.33294Z","steps":["trace[1398735733] 'agreement among raft nodes before linearized reading' (duration: 796.908959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.333005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.535858Z","time spent":"797.140945ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb\" "} {"level":"warn","ts":"2026-04-16T22:45:01.332829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.448401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T22:45:01.333213Z","caller":"traceutil/trace.go:171","msg":"trace[75779030] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"794.858762ms","start":"2026-04-16T22:45:00.538346Z","end":"2026-04-16T22:45:01.333205Z","steps":["trace[75779030] 'agreement among raft nodes before linearized reading' (duration: 794.321707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.333244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.538336Z","time spent":"794.900113ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T22:45:01.332794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050292421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T22:45:01.333365Z","caller":"traceutil/trace.go:171","msg":"trace[1623158663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb; range_end:; response_count:1; response_revision:1856; }","duration":"1.050907648s","start":"2026-04-16T22:45:00.282452Z","end":"2026-04-16T22:45:01.33336Z","steps":["trace[1623158663] 'agreement among raft nodes before linearized reading' (duration: 1.05025473s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.333393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.282431Z","time spent":"1.05095544s","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb\" "} {"level":"warn","ts":"2026-04-16T22:45:01.331587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071734732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:45:01.333513Z","caller":"traceutil/trace.go:171","msg":"trace[490723777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"1.073684336s","start":"2026-04-16T22:45:00.259778Z","end":"2026-04-16T22:45:01.333462Z","steps":["trace[490723777] 'agreement among raft nodes before linearized reading' (duration: 1.071732582s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.333575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.259752Z","time spent":"1.07380811s","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:45:01.334679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072536095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T22:45:01.334874Z","caller":"traceutil/trace.go:171","msg":"trace[2078682138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1856; }","duration":"1.072778911s","start":"2026-04-16T22:45:00.262078Z","end":"2026-04-16T22:45:01.334857Z","steps":["trace[2078682138] 'agreement among raft nodes before linearized reading' (duration: 1.070991141s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.334957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.262071Z","time spent":"1.072873934s","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T22:45:01.333461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.504537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T22:45:01.335259Z","caller":"traceutil/trace.go:171","msg":"trace[556877731] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"667.268777ms","start":"2026-04-16T22:45:00.667896Z","end":"2026-04-16T22:45:01.335165Z","steps":["trace[556877731] 'agreement among raft nodes before linearized reading' (duration: 665.010934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.335399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:00.667865Z","time spent":"667.437122ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T22:45:01.650544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.4116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:45:01.650667Z","caller":"traceutil/trace.go:171","msg":"trace[1400786016] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"310.806337ms","start":"2026-04-16T22:45:01.339847Z","end":"2026-04-16T22:45:01.650653Z","steps":["trace[1400786016] 'process raft request' (duration: 163.230722ms)","trace[1400786016] 'compare' (duration: 147.126513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:01.650732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:01.339831Z","time spent":"310.880569ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:01.651049Z","caller":"traceutil/trace.go:171","msg":"trace[142948814] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"302.148395ms","start":"2026-04-16T22:45:01.348872Z","end":"2026-04-16T22:45:01.651021Z","steps":["trace[142948814] 'process raft request' (duration: 301.737814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:01.651288Z","caller":"traceutil/trace.go:171","msg":"trace[1395297068] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"310.329014ms","start":"2026-04-16T22:45:01.340939Z","end":"2026-04-16T22:45:01.651268Z","steps":["trace[1395297068] 'read index received' (duration: 56.298794ms)","trace[1395297068] 'applied index is now lower than readState.Index' (duration: 254.02936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:01.651307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:01.348849Z","time spent":"302.374722ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:01.651401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.459808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:45:01.651444Z","caller":"traceutil/trace.go:171","msg":"trace[1711269594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"310.500158ms","start":"2026-04-16T22:45:01.340915Z","end":"2026-04-16T22:45:01.651415Z","steps":["trace[1711269594] 'agreement among raft nodes before linearized reading' (duration: 310.419786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.651463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:01.340907Z","time spent":"310.55208ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:45:01.651565Z","caller":"traceutil/trace.go:171","msg":"trace[45492130] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"301.992761ms","start":"2026-04-16T22:45:01.349558Z","end":"2026-04-16T22:45:01.65155Z","steps":["trace[45492130] 'process raft request' (duration: 301.352273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.651726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:01.349539Z","time spent":"302.131335ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:01.962923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.582395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:45:01.96306Z","caller":"traceutil/trace.go:171","msg":"trace[1248333175] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"186.296147ms","start":"2026-04-16T22:45:01.776742Z","end":"2026-04-16T22:45:01.963038Z","steps":["trace[1248333175] 'read index received' (duration: 19.491265ms)","trace[1248333175] 'applied index is now lower than readState.Index' (duration: 166.803302ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:01.963126Z","caller":"traceutil/trace.go:171","msg":"trace[1686777623] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"281.737384ms","start":"2026-04-16T22:45:01.681355Z","end":"2026-04-16T22:45:01.963092Z","steps":["trace[1686777623] 'process raft request' (duration: 114.868431ms)","trace[1686777623] 'compare' (duration: 166.38838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:01.963467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.714718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T22:45:01.963526Z","caller":"traceutil/trace.go:171","msg":"trace[975653563] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1861; }","duration":"186.82937ms","start":"2026-04-16T22:45:01.776676Z","end":"2026-04-16T22:45:01.963505Z","steps":["trace[975653563] 'agreement among raft nodes before linearized reading' (duration: 186.46697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.963615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.108755ms","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-04-16T22:45:01.963686Z","caller":"traceutil/trace.go:171","msg":"trace[1397067014] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1861; }","duration":"184.252839ms","start":"2026-04-16T22:45:01.779414Z","end":"2026-04-16T22:45:01.963667Z","steps":["trace[1397067014] 'agreement among raft nodes before linearized reading' (duration: 184.103995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:01.963735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.080238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T22:45:01.964391Z","caller":"traceutil/trace.go:171","msg":"trace[180611817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1861; }","duration":"114.183381ms","start":"2026-04-16T22:45:01.849589Z","end":"2026-04-16T22:45:01.963772Z","steps":["trace[180611817] 'agreement among raft nodes before linearized reading' (duration: 113.966705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:02.730819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.377342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:45:02.731053Z","caller":"traceutil/trace.go:171","msg":"trace[1049300670] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1935; }","duration":"558.62183ms","start":"2026-04-16T22:45:02.172412Z","end":"2026-04-16T22:45:02.731033Z","steps":["trace[1049300670] 'read index received' (duration: 209.9465ms)","trace[1049300670] 'applied index is now lower than readState.Index' (duration: 348.67436ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:02.731157Z","caller":"traceutil/trace.go:171","msg":"trace[70276262] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"763.160292ms","start":"2026-04-16T22:45:01.967984Z","end":"2026-04-16T22:45:02.731144Z","steps":["trace[70276262] 'process raft request' (duration: 414.360068ms)","trace[70276262] 'compare' (duration: 348.160306ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:02.731232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:01.967958Z","time spent":"763.231814ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:02.731487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.152006ms","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-04-16T22:45:02.731529Z","caller":"traceutil/trace.go:171","msg":"trace[325740892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1863; }","duration":"498.245908ms","start":"2026-04-16T22:45:02.233272Z","end":"2026-04-16T22:45:02.731517Z","steps":["trace[325740892] 'agreement among raft nodes before linearized reading' (duration: 498.164986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:02.731563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:02.233246Z","time spent":"498.307391ms","remote":"127.0.0.1:38518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:45:02.731624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.146845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-16T22:45:02.731761Z","caller":"traceutil/trace.go:171","msg":"trace[1590133749] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1863; }","duration":"559.356411ms","start":"2026-04-16T22:45:02.172376Z","end":"2026-04-16T22:45:02.731733Z","steps":["trace[1590133749] 'agreement among raft nodes before linearized reading' (duration: 558.949969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:02.73182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:02.172362Z","time spent":"559.446334ms","remote":"127.0.0.1:47260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-04-16T22:45:02.731877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.384601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T22:45:02.731918Z","caller":"traceutil/trace.go:171","msg":"trace[1700034877] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1863; }","duration":"391.464394ms","start":"2026-04-16T22:45:02.340443Z","end":"2026-04-16T22:45:02.731907Z","steps":["trace[1700034877] 'agreement among raft nodes before linearized reading' (duration: 391.30543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:02.731955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:02.340417Z","time spent":"391.531185ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-16T22:45:02.73198Z","caller":"traceutil/trace.go:171","msg":"trace[148544704] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"761.26638ms","start":"2026-04-16T22:45:01.970698Z","end":"2026-04-16T22:45:02.731965Z","steps":["trace[148544704] 'process raft request' (duration: 760.25166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:02.732112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:01.970682Z","time spent":"761.333511ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:02.732241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.423671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-16T22:45:02.732318Z","caller":"traceutil/trace.go:171","msg":"trace[1583199503] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb; range_end:; response_count:1; response_revision:1863; }","duration":"392.536054ms","start":"2026-04-16T22:45:02.339761Z","end":"2026-04-16T22:45:02.732297Z","steps":["trace[1583199503] 'agreement among raft nodes before linearized reading' (duration: 392.351859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:02.732372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:02.339746Z","time spent":"392.613396ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3768,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-547a5f09-1a56-4779-8408-cd9cec1086fb\" "} {"level":"info","ts":"2026-04-16T22:45:05.90183Z","caller":"traceutil/trace.go:171","msg":"trace[1271288749] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"124.972291ms","start":"2026-04-16T22:45:05.776829Z","end":"2026-04-16T22:45:05.901802Z","steps":["trace[1271288749] 'process raft request' (duration: 106.026663ms)","trace[1271288749] 'compare' (duration: 18.769943ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:11.127292Z","caller":"traceutil/trace.go:171","msg":"trace[312956182] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"344.750318ms","start":"2026-04-16T22:45:10.782502Z","end":"2026-04-16T22:45:11.127252Z","steps":["trace[312956182] 'process raft request' (duration: 332.213643ms)","trace[312956182] 'compare' (duration: 12.121712ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:11.12713Z","caller":"traceutil/trace.go:171","msg":"trace[1601368641] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2021; }","duration":"336.878142ms","start":"2026-04-16T22:45:10.79021Z","end":"2026-04-16T22:45:11.127088Z","steps":["trace[1601368641] 'read index received' (duration: 70.835662ms)","trace[1601368641] 'applied index is now lower than readState.Index' (duration: 266.041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:11.127446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.216764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-16T22:45:11.127575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:10.782486Z","time spent":"344.989446ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:11.127637Z","caller":"traceutil/trace.go:171","msg":"trace[1514222184] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1947; }","duration":"337.411439ms","start":"2026-04-16T22:45:10.790189Z","end":"2026-04-16T22:45:11.127601Z","steps":["trace[1514222184] 'agreement among raft nodes before linearized reading' (duration: 337.143201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:11.127686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:10.790181Z","time spent":"337.496042ms","remote":"127.0.0.1:56442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-16T22:45:16.597076Z","caller":"traceutil/trace.go:171","msg":"trace[492110172] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2064; }","duration":"333.977979ms","start":"2026-04-16T22:45:16.263063Z","end":"2026-04-16T22:45:16.597041Z","steps":["trace[492110172] 'read index received' (duration: 333.958648ms)","trace[492110172] 'applied index is now lower than readState.Index' (duration: 17.401µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:16.597237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.145724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:45:16.597298Z","caller":"traceutil/trace.go:171","msg":"trace[2048620959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1987; }","duration":"334.260789ms","start":"2026-04-16T22:45:16.263023Z","end":"2026-04-16T22:45:16.597283Z","steps":["trace[2048620959] 'agreement among raft nodes before linearized reading' (duration: 334.139635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:16.597358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:16.263002Z","time spent":"334.348431ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:45:16.597048Z","caller":"traceutil/trace.go:171","msg":"trace[1571735859] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"410.368383ms","start":"2026-04-16T22:45:16.186654Z","end":"2026-04-16T22:45:16.597022Z","steps":["trace[1571735859] 'process raft request' (duration: 410.229909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:16.597824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:16.18663Z","time spent":"411.129619ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:16.600009Z","caller":"traceutil/trace.go:171","msg":"trace[504594315] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"257.676099ms","start":"2026-04-16T22:45:16.342285Z","end":"2026-04-16T22:45:16.599961Z","steps":["trace[504594315] 'process raft request' (duration: 257.11448ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:36.019608Z","caller":"traceutil/trace.go:171","msg":"trace[1679757916] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"201.712957ms","start":"2026-04-16T22:45:35.817873Z","end":"2026-04-16T22:45:36.019586Z","steps":["trace[1679757916] 'process raft request' (duration: 201.566472ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:36.020099Z","caller":"traceutil/trace.go:171","msg":"trace[1464334467] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2182; }","duration":"186.34998ms","start":"2026-04-16T22:45:35.833741Z","end":"2026-04-16T22:45:36.020091Z","steps":["trace[1464334467] 'read index received' (duration: 186.34639ms)","trace[1464334467] 'applied index is now lower than readState.Index' (duration: 3.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:36.020433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.677501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:45:36.02065Z","caller":"traceutil/trace.go:171","msg":"trace[1007091425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2101; }","duration":"186.816627ms","start":"2026-04-16T22:45:35.833683Z","end":"2026-04-16T22:45:36.0205Z","steps":["trace[1007091425] 'agreement among raft nodes before linearized reading' (duration: 186.452233ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:36.447357Z","caller":"traceutil/trace.go:171","msg":"trace[1777180327] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"185.313629ms","start":"2026-04-16T22:45:36.262005Z","end":"2026-04-16T22:45:36.447319Z","steps":["trace[1777180327] 'read index received' (duration: 133.745965ms)","trace[1777180327] 'applied index is now lower than readState.Index' (duration: 51.566634ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:36.447533Z","caller":"traceutil/trace.go:171","msg":"trace[411511943] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"261.024081ms","start":"2026-04-16T22:45:36.186445Z","end":"2026-04-16T22:45:36.447469Z","steps":["trace[411511943] 'process raft request' (duration: 209.385964ms)","trace[411511943] 'compare' (duration: 51.374277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:36.448024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.998855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:45:36.44811Z","caller":"traceutil/trace.go:171","msg":"trace[549004387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2104; }","duration":"186.12272ms","start":"2026-04-16T22:45:36.261972Z","end":"2026-04-16T22:45:36.448095Z","steps":["trace[549004387] 'agreement among raft nodes before linearized reading' (duration: 185.479495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:36.448341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.956057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T22:45:36.448497Z","caller":"traceutil/trace.go:171","msg":"trace[1090075084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2104; }","duration":"112.207786ms","start":"2026-04-16T22:45:36.33626Z","end":"2026-04-16T22:45:36.448467Z","steps":["trace[1090075084] 'agreement among raft nodes before linearized reading' (duration: 111.663685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:37.251643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.142434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:45:37.251731Z","caller":"traceutil/trace.go:171","msg":"trace[689276938] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2105; }","duration":"184.2835ms","start":"2026-04-16T22:45:37.067429Z","end":"2026-04-16T22:45:37.251712Z","steps":["trace[689276938] 'count revisions from in-memory index tree' (duration: 184.042141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:37.252325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.962874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T22:45:37.252447Z","caller":"traceutil/trace.go:171","msg":"trace[610166741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2105; }","duration":"267.568187ms","start":"2026-04-16T22:45:36.984847Z","end":"2026-04-16T22:45:37.252416Z","steps":["trace[610166741] 'range keys from in-memory index tree' (duration: 266.696394ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:37.911879Z","caller":"traceutil/trace.go:171","msg":"trace[1738119090] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"363.496435ms","start":"2026-04-16T22:45:37.548347Z","end":"2026-04-16T22:45:37.911843Z","steps":["trace[1738119090] 'process raft request' (duration: 363.307858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:37.912312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:37.548318Z","time spent":"363.651501ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:38.606041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.153016ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-16T22:45:38.606509Z","caller":"traceutil/trace.go:171","msg":"trace[1625627920] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"151.618291ms","start":"2026-04-16T22:45:38.454868Z","end":"2026-04-16T22:45:38.606486Z","steps":["trace[1625627920] 'process raft request' (duration: 151.515697ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:38.606518Z","caller":"traceutil/trace.go:171","msg":"trace[2113536237] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2188; }","duration":"947.63328ms","start":"2026-04-16T22:45:37.658846Z","end":"2026-04-16T22:45:38.606479Z","steps":["trace[2113536237] 'read index received' (duration: 252.802333ms)","trace[2113536237] 'applied index is now lower than readState.Index' (duration: 694.826447ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:45:38.606539Z","caller":"traceutil/trace.go:171","msg":"trace[570764108] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"964.293504ms","start":"2026-04-16T22:45:37.642188Z","end":"2026-04-16T22:45:38.606481Z","steps":["trace[570764108] 'process raft request' (duration: 963.95357ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:38.606892Z","caller":"traceutil/trace.go:171","msg":"trace[550315188] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"581.624894ms","start":"2026-04-16T22:45:38.02525Z","end":"2026-04-16T22:45:38.606875Z","steps":["trace[550315188] 'process raft request' (duration: 581.081803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:38.60699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.342591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T22:45:38.607021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.02521Z","time spent":"581.725547ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:38.607038Z","caller":"traceutil/trace.go:171","msg":"trace[345572253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2111; }","duration":"892.442954ms","start":"2026-04-16T22:45:37.714581Z","end":"2026-04-16T22:45:38.607024Z","steps":["trace[345572253] 'agreement among raft nodes before linearized reading' (duration: 892.351921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:38.60708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:37.714552Z","time spent":"892.520577ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T22:45:38.606893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.020564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T22:45:38.607244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.442968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:45:38.607272Z","caller":"traceutil/trace.go:171","msg":"trace[1887089548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2111; }","duration":"570.528122ms","start":"2026-04-16T22:45:38.036735Z","end":"2026-04-16T22:45:38.607264Z","steps":["trace[1887089548] 'agreement among raft nodes before linearized reading' (duration: 570.449809ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:45:38.607272Z","caller":"traceutil/trace.go:171","msg":"trace[1169815308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"948.480882ms","start":"2026-04-16T22:45:37.658781Z","end":"2026-04-16T22:45:38.607262Z","steps":["trace[1169815308] 'agreement among raft nodes before linearized reading' (duration: 947.866989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:38.607298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.036707Z","time spent":"570.584534ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:45:38.607323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:37.658756Z","time spent":"948.554805ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:45:38.607236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.326439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:45:38.607433Z","caller":"traceutil/trace.go:171","msg":"trace[1195226435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2111; }","duration":"344.586809ms","start":"2026-04-16T22:45:38.262835Z","end":"2026-04-16T22:45:38.607422Z","steps":["trace[1195226435] 'agreement among raft nodes before linearized reading' (duration: 344.318858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:38.60746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.262813Z","time spent":"344.640831ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:45:38.607475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.907702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-16T22:45:38.607556Z","caller":"traceutil/trace.go:171","msg":"trace[857025348] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2111; }","duration":"133.028847ms","start":"2026-04-16T22:45:38.474498Z","end":"2026-04-16T22:45:38.607527Z","steps":["trace[857025348] 'agreement among raft nodes before linearized reading' (duration: 132.834549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:38.607103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:37.642164Z","time spent":"964.618667ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:45:38.607749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.515332ms","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":"info","ts":"2026-04-16T22:45:38.607798Z","caller":"traceutil/trace.go:171","msg":"trace[1394582716] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2111; }","duration":"705.606165ms","start":"2026-04-16T22:45:37.902176Z","end":"2026-04-16T22:45:38.607782Z","steps":["trace[1394582716] 'agreement among raft nodes before linearized reading' (duration: 705.461089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:38.607827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:37.902131Z","time spent":"705.689968ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-16T22:45:39.13644Z","caller":"traceutil/trace.go:171","msg":"trace[2055578768] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"523.027722ms","start":"2026-04-16T22:45:38.613373Z","end":"2026-04-16T22:45:39.136401Z","steps":["trace[2055578768] 'process raft request' (duration: 518.108789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:39.13664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.613343Z","time spent":"523.172957ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:39.137267Z","caller":"traceutil/trace.go:171","msg":"trace[1421211043] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"522.802164ms","start":"2026-04-16T22:45:38.614448Z","end":"2026-04-16T22:45:39.13725Z","steps":["trace[1421211043] 'process raft request' (duration: 522.676819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:39.137375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.614439Z","time spent":"522.892367ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:39.137533Z","caller":"traceutil/trace.go:171","msg":"trace[1084013051] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"521.592826ms","start":"2026-04-16T22:45:38.615929Z","end":"2026-04-16T22:45:39.137522Z","steps":["trace[1084013051] 'process raft request' (duration: 521.281114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:39.137594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.615922Z","time spent":"521.642178ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:45:39.137768Z","caller":"traceutil/trace.go:171","msg":"trace[258825287] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2193; }","duration":"448.706659ms","start":"2026-04-16T22:45:38.689049Z","end":"2026-04-16T22:45:39.137756Z","steps":["trace[258825287] 'read index received' (duration: 442.375332ms)","trace[258825287] 'applied index is now lower than readState.Index' (duration: 6.330007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:45:39.138032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.99091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a6f7c6335c37ca\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-04-16T22:45:39.138034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.653178ms","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-04-16T22:45:39.138076Z","caller":"traceutil/trace.go:171","msg":"trace[1299385368] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a6f7c6335c37ca; range_end:; response_count:1; response_revision:2115; }","duration":"449.074004ms","start":"2026-04-16T22:45:38.688991Z","end":"2026-04-16T22:45:39.138065Z","steps":["trace[1299385368] 'agreement among raft nodes before linearized reading' (duration: 448.923278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:45:39.138107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:45:38.688976Z","time spent":"449.124225ms","remote":"127.0.0.1:47308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a6f7c6335c37ca\" "} {"level":"info","ts":"2026-04-16T22:45:39.138103Z","caller":"traceutil/trace.go:171","msg":"trace[1258055586] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2115; }","duration":"218.796693ms","start":"2026-04-16T22:45:38.919289Z","end":"2026-04-16T22:45:39.138086Z","steps":["trace[1258055586] 'agreement among raft nodes before linearized reading' (duration: 218.675288ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:46:31.038286Z","caller":"traceutil/trace.go:171","msg":"trace[599589091] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"153.931567ms","start":"2026-04-16T22:46:30.884332Z","end":"2026-04-16T22:46:31.038263Z","steps":["trace[599589091] 'process raft request' (duration: 153.78684ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:46:31.039203Z","caller":"traceutil/trace.go:171","msg":"trace[1047639703] linearizableReadLoop","detail":"{readStateIndex:2534; appliedIndex:2531; }","duration":"112.933873ms","start":"2026-04-16T22:46:30.926247Z","end":"2026-04-16T22:46:31.039181Z","steps":["trace[1047639703] 'read index received' (duration: 111.928507ms)","trace[1047639703] 'applied index is now lower than readState.Index' (duration: 1.004406ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:46:31.03951Z","caller":"traceutil/trace.go:171","msg":"trace[645371444] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"151.372557ms","start":"2026-04-16T22:46:30.888124Z","end":"2026-04-16T22:46:31.039497Z","steps":["trace[645371444] 'process raft request' (duration: 150.921886ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:46:31.039669Z","caller":"traceutil/trace.go:171","msg":"trace[1800339201] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"151.353486ms","start":"2026-04-16T22:46:30.888212Z","end":"2026-04-16T22:46:31.039565Z","steps":["trace[1800339201] 'process raft request' (duration: 150.927376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:31.039958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.703018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T22:46:31.040006Z","caller":"traceutil/trace.go:171","msg":"trace[670259617] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2440; }","duration":"113.790533ms","start":"2026-04-16T22:46:30.926205Z","end":"2026-04-16T22:46:31.039996Z","steps":["trace[670259617] 'agreement among raft nodes before linearized reading' (duration: 113.631346ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:46:32.177208Z","caller":"traceutil/trace.go:171","msg":"trace[1625839064] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"136.322776ms","start":"2026-04-16T22:46:32.040861Z","end":"2026-04-16T22:46:32.177184Z","steps":["trace[1625839064] 'process raft request' (duration: 135.854914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.768913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.644355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:46:39.769611Z","caller":"traceutil/trace.go:171","msg":"trace[1039643564] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2638; }","duration":"507.148015ms","start":"2026-04-16T22:46:39.262442Z","end":"2026-04-16T22:46:39.76959Z","steps":["trace[1039643564] 'read index received' (duration: 155.839048ms)","trace[1039643564] 'applied index is now lower than readState.Index' (duration: 351.307567ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:46:39.769619Z","caller":"traceutil/trace.go:171","msg":"trace[1303762964] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"577.049903ms","start":"2026-04-16T22:46:39.192541Z","end":"2026-04-16T22:46:39.76959Z","steps":["trace[1303762964] 'process raft request' (duration: 225.633331ms)","trace[1303762964] 'compare' (duration: 350.507419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:46:39.769757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.304142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:46:39.769797Z","caller":"traceutil/trace.go:171","msg":"trace[1040046759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2543; }","duration":"507.384946ms","start":"2026-04-16T22:46:39.262402Z","end":"2026-04-16T22:46:39.769786Z","steps":["trace[1040046759] 'agreement among raft nodes before linearized reading' (duration: 507.296712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.769824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:46:39.262384Z","time spent":"507.434258ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:46:39.769805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:46:39.192525Z","time spent":"577.20184ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:46:39.769849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.725252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:46:39.769881Z","caller":"traceutil/trace.go:171","msg":"trace[75792409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2543; }","duration":"325.802166ms","start":"2026-04-16T22:46:39.44407Z","end":"2026-04-16T22:46:39.769873Z","steps":["trace[75792409] 'agreement among raft nodes before linearized reading' (duration: 325.663669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.769893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.342317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-16T22:46:39.769914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:46:39.444046Z","time spent":"325.861059ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T22:46:39.769946Z","caller":"traceutil/trace.go:171","msg":"trace[2145236268] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2543; }","duration":"118.448433ms","start":"2026-04-16T22:46:39.651482Z","end":"2026-04-16T22:46:39.769931Z","steps":["trace[2145236268] 'agreement among raft nodes before linearized reading' (duration: 118.281505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.770088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.099386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T22:46:39.77012Z","caller":"traceutil/trace.go:171","msg":"trace[326726287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2543; }","duration":"316.149878ms","start":"2026-04-16T22:46:39.453959Z","end":"2026-04-16T22:46:39.770109Z","steps":["trace[326726287] 'agreement among raft nodes before linearized reading' (duration: 316.059064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.770173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.134718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:46:39.770203Z","caller":"traceutil/trace.go:171","msg":"trace[1775062889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2543; }","duration":"320.17291ms","start":"2026-04-16T22:46:39.45002Z","end":"2026-04-16T22:46:39.770192Z","steps":["trace[1775062889] 'agreement among raft nodes before linearized reading' (duration: 320.087276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.770229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:46:39.450014Z","time spent":"320.209571ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:46:39.770318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.94604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T22:46:39.770353Z","caller":"traceutil/trace.go:171","msg":"trace[545173755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2543; }","duration":"173.015023ms","start":"2026-04-16T22:46:39.597329Z","end":"2026-04-16T22:46:39.770344Z","steps":["trace[545173755] 'agreement among raft nodes before linearized reading' (duration: 172.923099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:46:39.770144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:46:39.453949Z","time spent":"316.19066ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T22:46:58.087521Z","caller":"traceutil/trace.go:171","msg":"trace[1057207550] linearizableReadLoop","detail":"{readStateIndex:2750; appliedIndex:2749; }","duration":"102.222961ms","start":"2026-04-16T22:46:57.985274Z","end":"2026-04-16T22:46:58.087497Z","steps":["trace[1057207550] 'read index received' (duration: 13.881707ms)","trace[1057207550] 'applied index is now lower than readState.Index' (duration: 88.340254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:46:58.087683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.372888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:46:58.087767Z","caller":"traceutil/trace.go:171","msg":"trace[27907329] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2650; }","duration":"102.540776ms","start":"2026-04-16T22:46:57.985208Z","end":"2026-04-16T22:46:58.087749Z","steps":["trace[27907329] 'agreement among raft nodes before linearized reading' (duration: 102.391339ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:46:58.087576Z","caller":"traceutil/trace.go:171","msg":"trace[2053199661] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"121.325332ms","start":"2026-04-16T22:46:57.966199Z","end":"2026-04-16T22:46:58.087524Z","steps":["trace[2053199661] 'process raft request' (duration: 33.024721ms)","trace[2053199661] 'compare' (duration: 88.166465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:12.943545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.952381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:47:12.943639Z","caller":"traceutil/trace.go:171","msg":"trace[2107235367] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2727; }","duration":"119.10358ms","start":"2026-04-16T22:47:12.824513Z","end":"2026-04-16T22:47:12.943616Z","steps":["trace[2107235367] 'count revisions from in-memory index tree' (duration: 118.780543ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:13.384711Z","caller":"traceutil/trace.go:171","msg":"trace[1955141808] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"114.248564ms","start":"2026-04-16T22:47:13.27016Z","end":"2026-04-16T22:47:13.384409Z","steps":["trace[1955141808] 'process raft request' (duration: 114.036853ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:14.000101Z","caller":"traceutil/trace.go:171","msg":"trace[2050555963] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"667.456091ms","start":"2026-04-16T22:47:13.332621Z","end":"2026-04-16T22:47:14.000077Z","steps":["trace[2050555963] 'process raft request' (duration: 667.372857ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:14.000117Z","caller":"traceutil/trace.go:171","msg":"trace[271131356] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"667.673292ms","start":"2026-04-16T22:47:13.332382Z","end":"2026-04-16T22:47:14.000056Z","steps":["trace[271131356] 'process raft request' (duration: 590.593634ms)","trace[271131356] 'compare' (duration: 76.900479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:14.000329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:13.332357Z","time spent":"667.856691ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:47:14.000845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:13.332574Z","time spent":"667.584107ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:47:14.616955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.934703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:47:14.617176Z","caller":"traceutil/trace.go:171","msg":"trace[691229776] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"612.937663ms","start":"2026-04-16T22:47:14.004214Z","end":"2026-04-16T22:47:14.617152Z","steps":["trace[691229776] 'process raft request' (duration: 317.691285ms)","trace[691229776] 'compare' (duration: 293.986026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:47:14.617254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:14.004196Z","time spent":"613.024608ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:47:14.617935Z","caller":"traceutil/trace.go:171","msg":"trace[275207230] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2834; }","duration":"568.01124ms","start":"2026-04-16T22:47:14.049906Z","end":"2026-04-16T22:47:14.617917Z","steps":["trace[275207230] 'read index received' (duration: 272.016044ms)","trace[275207230] 'applied index is now lower than readState.Index' (duration: 295.992166ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:47:14.617994Z","caller":"traceutil/trace.go:171","msg":"trace[796801473] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"613.692201ms","start":"2026-04-16T22:47:14.004258Z","end":"2026-04-16T22:47:14.61795Z","steps":["trace[796801473] 'process raft request' (duration: 612.818957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:14.61815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.234762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T22:47:14.618185Z","caller":"traceutil/trace.go:171","msg":"trace[547018718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2732; }","duration":"568.319096ms","start":"2026-04-16T22:47:14.049855Z","end":"2026-04-16T22:47:14.618174Z","steps":["trace[547018718] 'agreement among raft nodes before linearized reading' (duration: 568.168278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:14.618188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:14.00424Z","time spent":"613.850178ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:47:14.618219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:14.049831Z","time spent":"568.379938ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:47:14.618281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.309665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-16T22:47:14.618344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.765178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:47:14.618369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.862735ms","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-04-16T22:47:14.618388Z","caller":"traceutil/trace.go:171","msg":"trace[1544781285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2732; }","duration":"353.832211ms","start":"2026-04-16T22:47:14.264541Z","end":"2026-04-16T22:47:14.618373Z","steps":["trace[1544781285] 'agreement among raft nodes before linearized reading' (duration: 353.759428ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:47:14.618408Z","caller":"traceutil/trace.go:171","msg":"trace[283678044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2732; }","duration":"133.94618ms","start":"2026-04-16T22:47:14.484452Z","end":"2026-04-16T22:47:14.618398Z","steps":["trace[283678044] 'agreement among raft nodes before linearized reading' (duration: 133.851755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:14.618421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:14.264527Z","time spent":"353.888673ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:47:14.618356Z","caller":"traceutil/trace.go:171","msg":"trace[399406793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2732; }","duration":"568.41271ms","start":"2026-04-16T22:47:14.049923Z","end":"2026-04-16T22:47:14.618336Z","steps":["trace[399406793] 'agreement among raft nodes before linearized reading' (duration: 568.127846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:14.618486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.055997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-16T22:47:14.618501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:14.049909Z","time spent":"568.584518ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T22:47:14.618534Z","caller":"traceutil/trace.go:171","msg":"trace[488884739] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2732; }","duration":"445.139621ms","start":"2026-04-16T22:47:14.173377Z","end":"2026-04-16T22:47:14.618516Z","steps":["trace[488884739] 'agreement among raft nodes before linearized reading' (duration: 445.014135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:47:14.618582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:47:14.173358Z","time spent":"445.212905ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-16T22:49:19.622399Z","caller":"traceutil/trace.go:171","msg":"trace[1185133768] transaction","detail":"{read_only:false; response_revision:3467; number_of_response:1; }","duration":"149.010264ms","start":"2026-04-16T22:49:19.473198Z","end":"2026-04-16T22:49:19.622208Z","steps":["trace[1185133768] 'process raft request' (duration: 102.924226ms)","trace[1185133768] 'compare' (duration: 45.180296ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:19.641019Z","caller":"traceutil/trace.go:171","msg":"trace[1421340163] transaction","detail":"{read_only:false; response_revision:3468; number_of_response:1; }","duration":"167.718117ms","start":"2026-04-16T22:49:19.473271Z","end":"2026-04-16T22:49:19.640989Z","steps":["trace[1421340163] 'process raft request' (duration: 167.564725ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:19.641372Z","caller":"traceutil/trace.go:171","msg":"trace[403954803] linearizableReadLoop","detail":"{readStateIndex:3602; appliedIndex:3600; }","duration":"166.810935ms","start":"2026-04-16T22:49:19.474541Z","end":"2026-04-16T22:49:19.641352Z","steps":["trace[403954803] 'read index received' (duration: 101.72248ms)","trace[403954803] 'applied index is now lower than readState.Index' (duration: 65.087525ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:19.641463Z","caller":"traceutil/trace.go:171","msg":"trace[832562745] transaction","detail":"{read_only:false; response_revision:3470; number_of_response:1; }","duration":"166.125037ms","start":"2026-04-16T22:49:19.475299Z","end":"2026-04-16T22:49:19.641424Z","steps":["trace[832562745] 'process raft request' (duration: 165.966505ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:19.641511Z","caller":"traceutil/trace.go:171","msg":"trace[1172713734] transaction","detail":"{read_only:false; response_revision:3469; number_of_response:1; }","duration":"166.804985ms","start":"2026-04-16T22:49:19.474624Z","end":"2026-04-16T22:49:19.641429Z","steps":["trace[1172713734] 'process raft request' (duration: 166.321419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:19.64184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.001877ms","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-04-16T22:49:19.641891Z","caller":"traceutil/trace.go:171","msg":"trace[1725232798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3470; }","duration":"167.370753ms","start":"2026-04-16T22:49:19.474507Z","end":"2026-04-16T22:49:19.641877Z","steps":["trace[1725232798] 'agreement among raft nodes before linearized reading' (duration: 166.931727ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:14.09089Z","caller":"traceutil/trace.go:171","msg":"trace[1881052780] transaction","detail":"{read_only:false; response_revision:3770; number_of_response:1; }","duration":"131.73782ms","start":"2026-04-16T22:50:13.959135Z","end":"2026-04-16T22:50:14.090873Z","steps":["trace[1881052780] 'process raft request' (duration: 124.514586ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:14.091156Z","caller":"traceutil/trace.go:171","msg":"trace[1338702346] transaction","detail":"{read_only:false; response_revision:3773; number_of_response:1; }","duration":"129.66012ms","start":"2026-04-16T22:50:13.96147Z","end":"2026-04-16T22:50:14.09113Z","steps":["trace[1338702346] 'process raft request' (duration: 129.615439ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:14.09128Z","caller":"traceutil/trace.go:171","msg":"trace[1210754994] transaction","detail":"{read_only:false; response_revision:3772; number_of_response:1; }","duration":"131.159567ms","start":"2026-04-16T22:50:13.960113Z","end":"2026-04-16T22:50:14.091273Z","steps":["trace[1210754994] 'process raft request' (duration: 130.929871ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:14.091435Z","caller":"traceutil/trace.go:171","msg":"trace[263731367] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"131.989387ms","start":"2026-04-16T22:50:13.959237Z","end":"2026-04-16T22:50:14.091226Z","steps":["trace[263731367] 'process raft request' (duration: 131.682159ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:16.859278Z","caller":"traceutil/trace.go:171","msg":"trace[1587381217] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"104.0831ms","start":"2026-04-16T22:50:16.755178Z","end":"2026-04-16T22:50:16.859261Z","steps":["trace[1587381217] 'process raft request' (duration: 103.999958ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:50.577335Z","caller":"traceutil/trace.go:171","msg":"trace[114258645] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"130.556392ms","start":"2026-04-16T22:50:50.446755Z","end":"2026-04-16T22:50:50.577312Z","steps":["trace[114258645] 'process raft request' (duration: 81.313433ms)","trace[114258645] 'compare' (duration: 49.145127ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:01.26527Z","caller":"traceutil/trace.go:171","msg":"trace[1220441187] transaction","detail":"{read_only:false; response_revision:4408; number_of_response:1; }","duration":"145.365358ms","start":"2026-04-16T22:51:01.119887Z","end":"2026-04-16T22:51:01.265253Z","steps":["trace[1220441187] 'process raft request' (duration: 145.048658ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:01.268771Z","caller":"traceutil/trace.go:171","msg":"trace[1190853662] transaction","detail":"{read_only:false; response_revision:4409; number_of_response:1; }","duration":"112.964432ms","start":"2026-04-16T22:51:01.155788Z","end":"2026-04-16T22:51:01.268752Z","steps":["trace[1190853662] 'process raft request' (duration: 112.770566ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:03.56227Z","caller":"traceutil/trace.go:171","msg":"trace[2142702234] transaction","detail":"{read_only:false; response_revision:4464; number_of_response:1; }","duration":"175.651389ms","start":"2026-04-16T22:51:03.386589Z","end":"2026-04-16T22:51:03.56224Z","steps":["trace[2142702234] 'process raft request' (duration: 175.321919ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:03.673528Z","caller":"traceutil/trace.go:171","msg":"trace[2024637891] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"100.988857ms","start":"2026-04-16T22:51:03.572509Z","end":"2026-04-16T22:51:03.673498Z","steps":["trace[2024637891] 'process raft request' (duration: 100.840392ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:05.233462Z","caller":"traceutil/trace.go:171","msg":"trace[236101372] transaction","detail":"{read_only:false; response_revision:4485; number_of_response:1; }","duration":"177.176164ms","start":"2026-04-16T22:51:05.056262Z","end":"2026-04-16T22:51:05.233438Z","steps":["trace[236101372] 'process raft request' (duration: 177.03556ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:05.250872Z","caller":"traceutil/trace.go:171","msg":"trace[2095581688] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"190.003964ms","start":"2026-04-16T22:51:05.060745Z","end":"2026-04-16T22:51:05.250749Z","steps":["trace[2095581688] 'process raft request' (duration: 189.633363ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:07.783103Z","caller":"traceutil/trace.go:171","msg":"trace[1161931521] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"125.644025ms","start":"2026-04-16T22:51:07.657418Z","end":"2026-04-16T22:51:07.783062Z","steps":["trace[1161931521] 'process raft request' (duration: 125.064587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:08.257247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.566359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:51:08.257335Z","caller":"traceutil/trace.go:171","msg":"trace[1538092651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4542; }","duration":"106.707633ms","start":"2026-04-16T22:51:08.150607Z","end":"2026-04-16T22:51:08.257314Z","steps":["trace[1538092651] 'range keys from in-memory index tree' (duration: 106.354322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:08.257379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.437657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:51:08.257462Z","caller":"traceutil/trace.go:171","msg":"trace[1649941270] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4542; }","duration":"289.570541ms","start":"2026-04-16T22:51:07.967873Z","end":"2026-04-16T22:51:08.257443Z","steps":["trace[1649941270] 'count revisions from in-memory index tree' (duration: 289.317824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:08.257251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.078935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:08.257536Z","caller":"traceutil/trace.go:171","msg":"trace[108663287] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4542; }","duration":"353.443147ms","start":"2026-04-16T22:51:07.904079Z","end":"2026-04-16T22:51:08.257522Z","steps":["trace[108663287] 'count revisions from in-memory index tree' (duration: 352.952091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:08.257593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:07.904044Z","time spent":"353.523949ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:51:08.257675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.181332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:16 size:486086"} {"level":"info","ts":"2026-04-16T22:51:08.257727Z","caller":"traceutil/trace.go:171","msg":"trace[857553710] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:16; response_revision:4542; }","duration":"363.254255ms","start":"2026-04-16T22:51:07.894453Z","end":"2026-04-16T22:51:08.257707Z","steps":["trace[857553710] 'range keys from in-memory index tree' (duration: 362.77271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:08.257777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:07.894434Z","time spent":"363.333977ms","remote":"127.0.0.1:47328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":16,"response size":486108,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-16T22:51:08.422883Z","caller":"traceutil/trace.go:171","msg":"trace[1178049336] linearizableReadLoop","detail":"{readStateIndex:4723; appliedIndex:4722; }","duration":"159.723303ms","start":"2026-04-16T22:51:08.263138Z","end":"2026-04-16T22:51:08.422862Z","steps":["trace[1178049336] 'read index received' (duration: 159.540147ms)","trace[1178049336] 'applied index is now lower than readState.Index' (duration: 182.486µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:08.423025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.869968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:08.423073Z","caller":"traceutil/trace.go:171","msg":"trace[656696049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4543; }","duration":"160.016432ms","start":"2026-04-16T22:51:08.263039Z","end":"2026-04-16T22:51:08.423055Z","steps":["trace[656696049] 'agreement among raft nodes before linearized reading' (duration: 159.919339ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:08.423068Z","caller":"traceutil/trace.go:171","msg":"trace[1258892016] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"160.078464ms","start":"2026-04-16T22:51:08.262905Z","end":"2026-04-16T22:51:08.422984Z","steps":["trace[1258892016] 'process raft request' (duration: 159.832926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:08.423277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.267669ms","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-04-16T22:51:08.423464Z","caller":"traceutil/trace.go:171","msg":"trace[1672180642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4543; }","duration":"106.502036ms","start":"2026-04-16T22:51:08.316939Z","end":"2026-04-16T22:51:08.423441Z","steps":["trace[1672180642] 'agreement among raft nodes before linearized reading' (duration: 106.198667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.630571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.513058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:51:10.630679Z","caller":"traceutil/trace.go:171","msg":"trace[286247863] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"475.967789ms","start":"2026-04-16T22:51:10.154693Z","end":"2026-04-16T22:51:10.63066Z","steps":["trace[286247863] 'process raft request' (duration: 213.293406ms)","trace[286247863] 'compare' (duration: 262.398504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:10.63074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:10.154673Z","time spent":"476.037082ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:10.636948Z","caller":"traceutil/trace.go:171","msg":"trace[520085649] linearizableReadLoop","detail":"{readStateIndex:4806; appliedIndex:4804; }","duration":"377.867707ms","start":"2026-04-16T22:51:10.259054Z","end":"2026-04-16T22:51:10.636922Z","steps":["trace[520085649] 'read index received' (duration: 108.944126ms)","trace[520085649] 'applied index is now lower than readState.Index' (duration: 268.922461ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:10.636973Z","caller":"traceutil/trace.go:171","msg":"trace[190351324] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"439.232394ms","start":"2026-04-16T22:51:10.197713Z","end":"2026-04-16T22:51:10.636946Z","steps":["trace[190351324] 'process raft request' (duration: 438.998837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.637057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.986291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:10.637082Z","caller":"traceutil/trace.go:171","msg":"trace[1050678419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4626; }","duration":"378.056763ms","start":"2026-04-16T22:51:10.25902Z","end":"2026-04-16T22:51:10.637076Z","steps":["trace[1050678419] 'agreement among raft nodes before linearized reading' (duration: 377.995221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.637101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:10.259004Z","time spent":"378.092915ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:51:10.637232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:10.197695Z","time spent":"439.379179ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4935,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:10.637496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.103854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-16T22:51:10.63753Z","caller":"traceutil/trace.go:171","msg":"trace[727256773] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4627; }","duration":"151.157205ms","start":"2026-04-16T22:51:10.486366Z","end":"2026-04-16T22:51:10.637523Z","steps":["trace[727256773] 'agreement among raft nodes before linearized reading' (duration: 151.027231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.637926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.615562ms","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-04-16T22:51:10.637984Z","caller":"traceutil/trace.go:171","msg":"trace[819706823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4627; }","duration":"199.686634ms","start":"2026-04-16T22:51:10.438279Z","end":"2026-04-16T22:51:10.637966Z","steps":["trace[819706823] 'agreement among raft nodes before linearized reading' (duration: 199.558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.638294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.726355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T22:51:10.638338Z","caller":"traceutil/trace.go:171","msg":"trace[1998611613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4627; }","duration":"152.821178ms","start":"2026-04-16T22:51:10.485505Z","end":"2026-04-16T22:51:10.638327Z","steps":["trace[1998611613] 'agreement among raft nodes before linearized reading' (duration: 152.720985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.638582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.721084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vfq9w.18a6f81460d809ef\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-16T22:51:10.638623Z","caller":"traceutil/trace.go:171","msg":"trace[4095500] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vfq9w.18a6f81460d809ef; range_end:; response_count:1; response_revision:4627; }","duration":"169.777506ms","start":"2026-04-16T22:51:10.468835Z","end":"2026-04-16T22:51:10.638613Z","steps":["trace[4095500] 'agreement among raft nodes before linearized reading' (duration: 169.676613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.638652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.107406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T22:51:10.638682Z","caller":"traceutil/trace.go:171","msg":"trace[1188524040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4627; }","duration":"118.162578ms","start":"2026-04-16T22:51:10.520512Z","end":"2026-04-16T22:51:10.638675Z","steps":["trace[1188524040] 'agreement among raft nodes before linearized reading' (duration: 118.084096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.639372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.928856ms","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-04-16T22:51:10.639406Z","caller":"traceutil/trace.go:171","msg":"trace[843299665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4627; }","duration":"126.985968ms","start":"2026-04-16T22:51:10.512412Z","end":"2026-04-16T22:51:10.639398Z","steps":["trace[843299665] 'agreement among raft nodes before linearized reading' (duration: 126.912076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.639419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.800475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:51:10.639457Z","caller":"traceutil/trace.go:171","msg":"trace[22828122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4627; }","duration":"209.867598ms","start":"2026-04-16T22:51:10.429579Z","end":"2026-04-16T22:51:10.639446Z","steps":["trace[22828122] 'agreement among raft nodes before linearized reading' (duration: 209.767944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.640021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.898072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-16T22:51:10.639842Z","caller":"traceutil/trace.go:171","msg":"trace[562300987] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"138.205775ms","start":"2026-04-16T22:51:10.501467Z","end":"2026-04-16T22:51:10.639672Z","steps":["trace[562300987] 'process raft request' (duration: 135.801998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:10.642472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.457544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55d05a61-02db-418a-8121-2b40b5a8bde7\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-04-16T22:51:10.643687Z","caller":"traceutil/trace.go:171","msg":"trace[543346238] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55d05a61-02db-418a-8121-2b40b5a8bde7; range_end:; response_count:1; response_revision:4627; }","duration":"265.656148ms","start":"2026-04-16T22:51:10.378014Z","end":"2026-04-16T22:51:10.64367Z","steps":["trace[543346238] 'agreement among raft nodes before linearized reading' (duration: 261.447244ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:10.643117Z","caller":"traceutil/trace.go:171","msg":"trace[909330210] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4627; }","duration":"285.023211ms","start":"2026-04-16T22:51:10.358078Z","end":"2026-04-16T22:51:10.643101Z","steps":["trace[909330210] 'agreement among raft nodes before linearized reading' (duration: 279.288359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:11.876781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.516984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55d05a61-02db-418a-8121-2b40b5a8bde7\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-04-16T22:51:11.876928Z","caller":"traceutil/trace.go:171","msg":"trace[544558418] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55d05a61-02db-418a-8121-2b40b5a8bde7; range_end:; response_count:1; response_revision:4648; }","duration":"228.69808ms","start":"2026-04-16T22:51:11.648208Z","end":"2026-04-16T22:51:11.876906Z","steps":["trace[544558418] 'range keys from in-memory index tree' (duration: 228.457782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.942091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.151228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T22:51:12.942148Z","caller":"traceutil/trace.go:171","msg":"trace[914135504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4651; }","duration":"281.259451ms","start":"2026-04-16T22:51:12.660875Z","end":"2026-04-16T22:51:12.942134Z","steps":["trace[914135504] 'range keys from in-memory index tree' (duration: 280.985693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.942142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.799387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-16T22:51:12.942196Z","caller":"traceutil/trace.go:171","msg":"trace[1442918587] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4651; }","duration":"280.866179ms","start":"2026-04-16T22:51:12.661315Z","end":"2026-04-16T22:51:12.942182Z","steps":["trace[1442918587] 'range keys from in-memory index tree' (duration: 280.729805ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:12.942662Z","caller":"traceutil/trace.go:171","msg":"trace[400569452] linearizableReadLoop","detail":"{readStateIndex:4833; appliedIndex:4832; }","duration":"275.87774ms","start":"2026-04-16T22:51:12.666777Z","end":"2026-04-16T22:51:12.942655Z","steps":["trace[400569452] 'read index received' (duration: 265.776087ms)","trace[400569452] 'applied index is now lower than readState.Index' (duration: 10.101083ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:12.942828Z","caller":"traceutil/trace.go:171","msg":"trace[504004787] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"279.870978ms","start":"2026-04-16T22:51:12.662949Z","end":"2026-04-16T22:51:12.94282Z","steps":["trace[504004787] 'process raft request' (duration: 269.576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.942967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.184519ms","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-04-16T22:51:12.942992Z","caller":"traceutil/trace.go:171","msg":"trace[2129359431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4652; }","duration":"276.22031ms","start":"2026-04-16T22:51:12.666765Z","end":"2026-04-16T22:51:12.942986Z","steps":["trace[2129359431] 'agreement among raft nodes before linearized reading' (duration: 276.154768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.943132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.125138ms","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-04-16T22:51:12.943153Z","caller":"traceutil/trace.go:171","msg":"trace[1035973713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4652; }","duration":"276.155218ms","start":"2026-04-16T22:51:12.666993Z","end":"2026-04-16T22:51:12.943148Z","steps":["trace[1035973713] 'agreement among raft nodes before linearized reading' (duration: 276.090196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.943527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.000576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T22:51:12.945606Z","caller":"traceutil/trace.go:171","msg":"trace[2045026739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4652; }","duration":"274.105803ms","start":"2026-04-16T22:51:12.671488Z","end":"2026-04-16T22:51:12.945594Z","steps":["trace[2045026739] 'agreement among raft nodes before linearized reading' (duration: 272.001776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.943567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.454925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:12.945691Z","caller":"traceutil/trace.go:171","msg":"trace[895946180] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4652; }","duration":"271.554721ms","start":"2026-04-16T22:51:12.674103Z","end":"2026-04-16T22:51:12.945658Z","steps":["trace[895946180] 'agreement among raft nodes before linearized reading' (duration: 269.440245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:12.943607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.675354ms","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-04-16T22:51:12.945785Z","caller":"traceutil/trace.go:171","msg":"trace[844013079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4652; }","duration":"277.851803ms","start":"2026-04-16T22:51:12.667921Z","end":"2026-04-16T22:51:12.945772Z","steps":["trace[844013079] 'agreement among raft nodes before linearized reading' (duration: 275.661584ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.132816Z","caller":"traceutil/trace.go:171","msg":"trace[878265866] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"187.352406ms","start":"2026-04-16T22:51:12.945447Z","end":"2026-04-16T22:51:13.132799Z","steps":["trace[878265866] 'process raft request' (duration: 182.873102ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.134369Z","caller":"traceutil/trace.go:171","msg":"trace[975008384] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"188.109522ms","start":"2026-04-16T22:51:12.946247Z","end":"2026-04-16T22:51:13.134357Z","steps":["trace[975008384] 'process raft request' (duration: 187.926636ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.134783Z","caller":"traceutil/trace.go:171","msg":"trace[195358526] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"188.253135ms","start":"2026-04-16T22:51:12.946521Z","end":"2026-04-16T22:51:13.134774Z","steps":["trace[195358526] 'process raft request' (duration: 187.77291ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.135067Z","caller":"traceutil/trace.go:171","msg":"trace[1710291870] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4833; }","duration":"188.558694ms","start":"2026-04-16T22:51:12.946485Z","end":"2026-04-16T22:51:13.135043Z","steps":["trace[1710291870] 'read index received' (duration: 180.830496ms)","trace[1710291870] 'applied index is now lower than readState.Index' (duration: 7.727298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:13.135275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.768612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-46bb0481-a734-4920-8a4d-909f75917f1b\" ","response":"range_response_count:1 size:4081"} {"level":"info","ts":"2026-04-16T22:51:13.135335Z","caller":"traceutil/trace.go:171","msg":"trace[1872966688] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-46bb0481-a734-4920-8a4d-909f75917f1b; range_end:; response_count:1; response_revision:4658; }","duration":"188.860824ms","start":"2026-04-16T22:51:12.946461Z","end":"2026-04-16T22:51:13.135322Z","steps":["trace[1872966688] 'agreement among raft nodes before linearized reading' (duration: 188.680818ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.135464Z","caller":"traceutil/trace.go:171","msg":"trace[1858149583] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"187.657946ms","start":"2026-04-16T22:51:12.947795Z","end":"2026-04-16T22:51:13.135453Z","steps":["trace[1858149583] 'process raft request' (duration: 186.919293ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.135636Z","caller":"traceutil/trace.go:171","msg":"trace[769369716] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"186.122897ms","start":"2026-04-16T22:51:12.949418Z","end":"2026-04-16T22:51:13.135541Z","steps":["trace[769369716] 'process raft request' (duration: 185.58732ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:13.135149Z","caller":"traceutil/trace.go:171","msg":"trace[1233026931] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"187.211012ms","start":"2026-04-16T22:51:12.947907Z","end":"2026-04-16T22:51:13.135118Z","steps":["trace[1233026931] 'process raft request' (duration: 187.009445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:17.756707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757019792298719994,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T22:51:17.825924Z","caller":"traceutil/trace.go:171","msg":"trace[150112674] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"645.433641ms","start":"2026-04-16T22:51:17.180465Z","end":"2026-04-16T22:51:17.825898Z","steps":["trace[150112674] 'process raft request' (duration: 645.364338ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:17.826087Z","caller":"traceutil/trace.go:171","msg":"trace[1495993417] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"646.248647ms","start":"2026-04-16T22:51:17.179787Z","end":"2026-04-16T22:51:17.826035Z","steps":["trace[1495993417] 'process raft request' (duration: 645.872385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:17.826393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.180459Z","time spent":"645.773921ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:17.826532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.179776Z","time spent":"646.555206ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:18.161606Z","caller":"traceutil/trace.go:171","msg":"trace[22952383] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"812.060203ms","start":"2026-04-16T22:51:17.34931Z","end":"2026-04-16T22:51:18.16137Z","steps":["trace[22952383] 'process raft request' (duration: 811.753723ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:18.161632Z","caller":"traceutil/trace.go:171","msg":"trace[1077963877] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"900.685608ms","start":"2026-04-16T22:51:17.26092Z","end":"2026-04-16T22:51:18.161605Z","steps":["trace[1077963877] 'process raft request' (duration: 851.372634ms)","trace[1077963877] 'compare' (duration: 48.27953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:18.161801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.260898Z","time spent":"900.835263ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:18.161805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.349271Z","time spent":"812.448725ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:18.161635Z","caller":"traceutil/trace.go:171","msg":"trace[799330267] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"810.516723ms","start":"2026-04-16T22:51:17.351086Z","end":"2026-04-16T22:51:18.161603Z","steps":["trace[799330267] 'process raft request' (duration: 810.102639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.161962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.351073Z","time spent":"810.801301ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:18.219664Z","caller":"traceutil/trace.go:171","msg":"trace[1743562556] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4898; }","duration":"963.770091ms","start":"2026-04-16T22:51:17.25585Z","end":"2026-04-16T22:51:18.21962Z","steps":["trace[1743562556] 'read index received' (duration: 569.855448ms)","trace[1743562556] 'applied index is now lower than readState.Index' (duration: 393.912583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:18.219894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0371075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T22:51:18.219967Z","caller":"traceutil/trace.go:171","msg":"trace[1628886567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4719; }","duration":"1.037199844s","start":"2026-04-16T22:51:17.182748Z","end":"2026-04-16T22:51:18.219948Z","steps":["trace[1628886567] 'agreement among raft nodes before linearized reading' (duration: 1.036990357s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.220016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.18274Z","time spent":"1.037267585s","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:51:18.220044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039632033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:51:18.22017Z","caller":"traceutil/trace.go:171","msg":"trace[1268674351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4719; }","duration":"1.039773327s","start":"2026-04-16T22:51:17.180372Z","end":"2026-04-16T22:51:18.220146Z","steps":["trace[1268674351] 'agreement among raft nodes before linearized reading' (duration: 1.039514399s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.22023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.180367Z","time spent":"1.039849709s","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:51:18.226885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.070092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:51:18.22708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.599457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-04-16T22:51:18.227132Z","caller":"traceutil/trace.go:171","msg":"trace[215823605] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4720; }","duration":"677.643398ms","start":"2026-04-16T22:51:17.549474Z","end":"2026-04-16T22:51:18.227117Z","steps":["trace[215823605] 'agreement among raft nodes before linearized reading' (duration: 677.542675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.227126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.333014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5786874d97-7925d.18a6f81606404358\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-04-16T22:51:18.227172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.549429Z","time spent":"677.736202ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12995,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-16T22:51:18.227193Z","caller":"traceutil/trace.go:171","msg":"trace[1433166335] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5786874d97-7925d.18a6f81606404358; range_end:; response_count:1; response_revision:4720; }","duration":"675.454878ms","start":"2026-04-16T22:51:17.55172Z","end":"2026-04-16T22:51:18.227175Z","steps":["trace[1433166335] 'agreement among raft nodes before linearized reading' (duration: 675.115466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.22724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.551709Z","time spent":"675.52197ms","remote":"127.0.0.1:47308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":920,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5786874d97-7925d.18a6f81606404358\" "} {"level":"warn","ts":"2026-04-16T22:51:18.22734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.605913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:51:18.227384Z","caller":"traceutil/trace.go:171","msg":"trace[204734314] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4720; }","duration":"389.756229ms","start":"2026-04-16T22:51:17.837618Z","end":"2026-04-16T22:51:18.227374Z","steps":["trace[204734314] 'agreement among raft nodes before linearized reading' (duration: 389.48081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.227409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.837599Z","time spent":"389.803049ms","remote":"127.0.0.1:47530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":35,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:51:18.226925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.882556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-16T22:51:18.227482Z","caller":"traceutil/trace.go:171","msg":"trace[1638659951] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4720; }","duration":"658.466346ms","start":"2026-04-16T22:51:17.569002Z","end":"2026-04-16T22:51:18.227468Z","steps":["trace[1638659951] 'agreement among raft nodes before linearized reading' (duration: 657.798183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.227516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.568986Z","time spent":"658.522438ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-16T22:51:18.22699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.07409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:51:18.227619Z","caller":"traceutil/trace.go:171","msg":"trace[142468204] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4720; }","duration":"566.73122ms","start":"2026-04-16T22:51:17.660858Z","end":"2026-04-16T22:51:18.227589Z","steps":["trace[142468204] 'agreement among raft nodes before linearized reading' (duration: 566.103209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.227427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.466787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:18.227726Z","caller":"traceutil/trace.go:171","msg":"trace[1397843243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4720; }","duration":"965.788858ms","start":"2026-04-16T22:51:17.261927Z","end":"2026-04-16T22:51:18.227716Z","steps":["trace[1397843243] 'agreement among raft nodes before linearized reading' (duration: 964.830656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.227745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.660833Z","time spent":"566.868635ms","remote":"127.0.0.1:56424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:51:18.227793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.261916Z","time spent":"965.86485ms","remote":"127.0.0.1:47220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:51:18.227214Z","caller":"traceutil/trace.go:171","msg":"trace[1955686031] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4720; }","duration":"547.394064ms","start":"2026-04-16T22:51:17.679702Z","end":"2026-04-16T22:51:18.227096Z","steps":["trace[1955686031] 'agreement among raft nodes before linearized reading' (duration: 547.071602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.228045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:17.679673Z","time spent":"548.351164ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-16T22:51:18.444473Z","caller":"traceutil/trace.go:171","msg":"trace[748789026] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4907; }","duration":"195.253815ms","start":"2026-04-16T22:51:18.249194Z","end":"2026-04-16T22:51:18.444448Z","steps":["trace[748789026] 'read index received' (duration: 194.990447ms)","trace[748789026] 'applied index is now lower than readState.Index' (duration: 262.448µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:18.444757Z","caller":"traceutil/trace.go:171","msg":"trace[2142993691] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"214.515221ms","start":"2026-04-16T22:51:18.230215Z","end":"2026-04-16T22:51:18.44473Z","steps":["trace[2142993691] 'process raft request' (duration: 213.974914ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:18.444834Z","caller":"traceutil/trace.go:171","msg":"trace[556652760] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"206.014675ms","start":"2026-04-16T22:51:18.238807Z","end":"2026-04-16T22:51:18.444821Z","steps":["trace[556652760] 'process raft request' (duration: 205.565441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:18.444777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.926663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-46bb0481-a734-4920-8a4d-909f75917f1b\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-04-16T22:51:18.44507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.970981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:18.445067Z","caller":"traceutil/trace.go:171","msg":"trace[1882032251] range","detail":"{range_begin:/registry/persistentvolumes/pvc-46bb0481-a734-4920-8a4d-909f75917f1b; range_end:; response_count:1; response_revision:4724; }","duration":"214.221472ms","start":"2026-04-16T22:51:18.230805Z","end":"2026-04-16T22:51:18.445027Z","steps":["trace[1882032251] 'agreement among raft nodes before linearized reading' (duration: 213.752157ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:18.445116Z","caller":"traceutil/trace.go:171","msg":"trace[1134200719] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4724; }","duration":"209.036733ms","start":"2026-04-16T22:51:18.236064Z","end":"2026-04-16T22:51:18.445101Z","steps":["trace[1134200719] 'agreement among raft nodes before linearized reading' (duration: 208.978591ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:19.245587Z","caller":"traceutil/trace.go:171","msg":"trace[782306622] linearizableReadLoop","detail":"{readStateIndex:4975; appliedIndex:4973; }","duration":"250.919869ms","start":"2026-04-16T22:51:18.99464Z","end":"2026-04-16T22:51:19.24556Z","steps":["trace[782306622] 'read index received' (duration: 216.529306ms)","trace[782306622] 'applied index is now lower than readState.Index' (duration: 34.389363ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:19.245935Z","caller":"traceutil/trace.go:171","msg":"trace[251993682] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"287.450918ms","start":"2026-04-16T22:51:18.958463Z","end":"2026-04-16T22:51:19.245914Z","steps":["trace[251993682] 'process raft request' (duration: 252.690774ms)","trace[251993682] 'compare' (duration: 34.197166ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:19.246029Z","caller":"traceutil/trace.go:171","msg":"trace[951107838] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"274.713964ms","start":"2026-04-16T22:51:18.971289Z","end":"2026-04-16T22:51:19.246003Z","steps":["trace[951107838] 'process raft request' (duration: 274.204137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:19.246064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.415925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-16T22:51:19.246117Z","caller":"traceutil/trace.go:171","msg":"trace[345616447] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4790; }","duration":"251.517018ms","start":"2026-04-16T22:51:18.994586Z","end":"2026-04-16T22:51:19.246103Z","steps":["trace[345616447] 'agreement among raft nodes before linearized reading' (duration: 251.345013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:19.246333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.355253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-16T22:51:19.246366Z","caller":"traceutil/trace.go:171","msg":"trace[525066603] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4790; }","duration":"201.456326ms","start":"2026-04-16T22:51:19.044901Z","end":"2026-04-16T22:51:19.246358Z","steps":["trace[525066603] 'agreement among raft nodes before linearized reading' (duration: 201.293851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:19.247292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.087376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-16T22:51:19.247442Z","caller":"traceutil/trace.go:171","msg":"trace[199941968] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4790; }","duration":"232.478679ms","start":"2026-04-16T22:51:19.014946Z","end":"2026-04-16T22:51:19.247424Z","steps":["trace[199941968] 'agreement among raft nodes before linearized reading' (duration: 231.236038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:20.321861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.204314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T22:51:20.321938Z","caller":"traceutil/trace.go:171","msg":"trace[1436588042] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4802; }","duration":"156.306457ms","start":"2026-04-16T22:51:20.165613Z","end":"2026-04-16T22:51:20.32192Z","steps":["trace[1436588042] 'range keys from in-memory index tree' (duration: 156.08862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:20.322013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.969154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5786874d97-7925d.18a6f81606404358\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-16T22:51:20.322084Z","caller":"traceutil/trace.go:171","msg":"trace[1994123237] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5786874d97-7925d.18a6f81606404358; range_end:; response_count:1; response_revision:4802; }","duration":"170.099869ms","start":"2026-04-16T22:51:20.151964Z","end":"2026-04-16T22:51:20.322064Z","steps":["trace[1994123237] 'range keys from in-memory index tree' (duration: 169.802589ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:20.348158Z","caller":"traceutil/trace.go:171","msg":"trace[1687591074] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"181.43115ms","start":"2026-04-16T22:51:20.166698Z","end":"2026-04-16T22:51:20.348129Z","steps":["trace[1687591074] 'process raft request' (duration: 181.221333ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:20.351511Z","caller":"traceutil/trace.go:171","msg":"trace[1386746979] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"183.720166ms","start":"2026-04-16T22:51:20.167767Z","end":"2026-04-16T22:51:20.351488Z","steps":["trace[1386746979] 'process raft request' (duration: 183.516889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:21.404252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.252182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:51:21.40448Z","caller":"traceutil/trace.go:171","msg":"trace[1159261724] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"512.191378ms","start":"2026-04-16T22:51:20.89225Z","end":"2026-04-16T22:51:21.404441Z","steps":["trace[1159261724] 'process raft request' (duration: 362.676528ms)","trace[1159261724] 'compare' (duration: 149.125508ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:21.404678Z","caller":"traceutil/trace.go:171","msg":"trace[2053652246] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"152.623962ms","start":"2026-04-16T22:51:21.252036Z","end":"2026-04-16T22:51:21.40466Z","steps":["trace[2053652246] 'process raft request' (duration: 152.464287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:21.404702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:20.892237Z","time spent":"512.352664ms","remote":"127.0.0.1:47308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:21.404668Z","caller":"traceutil/trace.go:171","msg":"trace[250840470] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"435.982464ms","start":"2026-04-16T22:51:20.968657Z","end":"2026-04-16T22:51:21.40464Z","steps":["trace[250840470] 'process raft request' (duration: 435.768827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:21.404962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.135817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"warn","ts":"2026-04-16T22:51:21.404959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:20.968619Z","time spent":"436.243853ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:21.404819Z","caller":"traceutil/trace.go:171","msg":"trace[1974014651] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:5001; }","duration":"511.989272ms","start":"2026-04-16T22:51:20.89282Z","end":"2026-04-16T22:51:21.404809Z","steps":["trace[1974014651] 'read index received' (duration: 362.089889ms)","trace[1974014651] 'applied index is now lower than readState.Index' (duration: 149.898203ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:21.404993Z","caller":"traceutil/trace.go:171","msg":"trace[2125865682] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4819; }","duration":"512.185199ms","start":"2026-04-16T22:51:20.892799Z","end":"2026-04-16T22:51:21.404984Z","steps":["trace[2125865682] 'agreement among raft nodes before linearized reading' (duration: 512.040364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:21.405181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:20.89279Z","time spent":"512.375205ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-16T22:51:21.405243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.779971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-04-16T22:51:21.40512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.640344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:21.405295Z","caller":"traceutil/trace.go:171","msg":"trace[1500983547] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4819; }","duration":"104.838453ms","start":"2026-04-16T22:51:21.300442Z","end":"2026-04-16T22:51:21.40528Z","steps":["trace[1500983547] 'agreement among raft nodes before linearized reading' (duration: 104.701898ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:21.40533Z","caller":"traceutil/trace.go:171","msg":"trace[895101338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4819; }","duration":"142.877042ms","start":"2026-04-16T22:51:21.262433Z","end":"2026-04-16T22:51:21.40531Z","steps":["trace[895101338] 'agreement among raft nodes before linearized reading' (duration: 142.647584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:21.405604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.24649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T22:51:21.405674Z","caller":"traceutil/trace.go:171","msg":"trace[2064412125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4819; }","duration":"131.355354ms","start":"2026-04-16T22:51:21.274303Z","end":"2026-04-16T22:51:21.405658Z","steps":["trace[2064412125] 'agreement among raft nodes before linearized reading' (duration: 131.130297ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:26.873584Z","caller":"traceutil/trace.go:171","msg":"trace[1466744111] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"168.821934ms","start":"2026-04-16T22:51:26.704744Z","end":"2026-04-16T22:51:26.873566Z","steps":["trace[1466744111] 'read index received' (duration: 168.677279ms)","trace[1466744111] 'applied index is now lower than readState.Index' (duration: 143.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:26.873627Z","caller":"traceutil/trace.go:171","msg":"trace[2078262573] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"205.722323ms","start":"2026-04-16T22:51:26.667877Z","end":"2026-04-16T22:51:26.8736Z","steps":["trace[2078262573] 'process raft request' (duration: 205.564888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:26.873743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.993139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-16T22:51:26.873773Z","caller":"traceutil/trace.go:171","msg":"trace[2076227191] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4912; }","duration":"169.079992ms","start":"2026-04-16T22:51:26.704683Z","end":"2026-04-16T22:51:26.873764Z","steps":["trace[2076227191] 'agreement among raft nodes before linearized reading' (duration: 168.983669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:30.91841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.433352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"warn","ts":"2026-04-16T22:51:30.918478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.104517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-16T22:51:30.91852Z","caller":"traceutil/trace.go:171","msg":"trace[843476802] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4954; }","duration":"233.593298ms","start":"2026-04-16T22:51:30.684903Z","end":"2026-04-16T22:51:30.918496Z","steps":["trace[843476802] 'range keys from in-memory index tree' (duration: 233.250597ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:30.918539Z","caller":"traceutil/trace.go:171","msg":"trace[896749836] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4954; }","duration":"172.192701ms","start":"2026-04-16T22:51:30.74633Z","end":"2026-04-16T22:51:30.918523Z","steps":["trace[896749836] 'range keys from in-memory index tree' (duration: 171.987924ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.361604Z","caller":"traceutil/trace.go:171","msg":"trace[38007595] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5143; }","duration":"355.977693ms","start":"2026-04-16T22:51:31.005604Z","end":"2026-04-16T22:51:31.361582Z","steps":["trace[38007595] 'read index received' (duration: 355.773196ms)","trace[38007595] 'applied index is now lower than readState.Index' (duration: 203.607µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:31.361711Z","caller":"traceutil/trace.go:171","msg":"trace[848434612] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"431.105514ms","start":"2026-04-16T22:51:30.930594Z","end":"2026-04-16T22:51:31.361699Z","steps":["trace[848434612] 'process raft request' (duration: 430.854305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:31.36181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:30.930582Z","time spent":"431.154236ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:31.361842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.44547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:51:31.361927Z","caller":"traceutil/trace.go:171","msg":"trace[880967160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4955; }","duration":"253.580603ms","start":"2026-04-16T22:51:31.108324Z","end":"2026-04-16T22:51:31.361905Z","steps":["trace[880967160] 'agreement among raft nodes before linearized reading' (duration: 253.423968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:31.362137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.790902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:51:31.362131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.930685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:31.362189Z","caller":"traceutil/trace.go:171","msg":"trace[1967459955] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4955; }","duration":"216.871404ms","start":"2026-04-16T22:51:31.145305Z","end":"2026-04-16T22:51:31.362176Z","steps":["trace[1967459955] 'agreement among raft nodes before linearized reading' (duration: 216.799092ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.362234Z","caller":"traceutil/trace.go:171","msg":"trace[1586302870] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4955; }","duration":"177.08397ms","start":"2026-04-16T22:51:31.185119Z","end":"2026-04-16T22:51:31.362203Z","steps":["trace[1586302870] 'agreement among raft nodes before linearized reading' (duration: 176.898304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:31.362871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.246336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:31.36337Z","caller":"traceutil/trace.go:171","msg":"trace[2104112941] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4955; }","duration":"357.776154ms","start":"2026-04-16T22:51:31.00557Z","end":"2026-04-16T22:51:31.363346Z","steps":["trace[2104112941] 'agreement among raft nodes before linearized reading' (duration: 356.369716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:31.366264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:31.005557Z","time spent":"358.408775ms","remote":"127.0.0.1:38566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-16T22:51:31.492256Z","caller":"traceutil/trace.go:171","msg":"trace[1920307045] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"123.852003ms","start":"2026-04-16T22:51:31.368375Z","end":"2026-04-16T22:51:31.492227Z","steps":["trace[1920307045] 'process raft request' (duration: 83.848811ms)","trace[1920307045] 'compare' (duration: 39.828927ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:31.525123Z","caller":"traceutil/trace.go:171","msg":"trace[549898647] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"156.023159ms","start":"2026-04-16T22:51:31.369073Z","end":"2026-04-16T22:51:31.525096Z","steps":["trace[549898647] 'process raft request' (duration: 155.812662ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.52546Z","caller":"traceutil/trace.go:171","msg":"trace[1342232427] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"155.974309ms","start":"2026-04-16T22:51:31.369451Z","end":"2026-04-16T22:51:31.525425Z","steps":["trace[1342232427] 'process raft request' (duration: 155.596895ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.525537Z","caller":"traceutil/trace.go:171","msg":"trace[630581095] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"154.680435ms","start":"2026-04-16T22:51:31.37083Z","end":"2026-04-16T22:51:31.52551Z","steps":["trace[630581095] 'process raft request' (duration: 154.443167ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.526415Z","caller":"traceutil/trace.go:171","msg":"trace[644486806] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"147.485691ms","start":"2026-04-16T22:51:31.378911Z","end":"2026-04-16T22:51:31.526396Z","steps":["trace[644486806] 'process raft request' (duration: 146.492108ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.734351Z","caller":"traceutil/trace.go:171","msg":"trace[607778183] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5157; }","duration":"120.106247ms","start":"2026-04-16T22:51:31.614226Z","end":"2026-04-16T22:51:31.734332Z","steps":["trace[607778183] 'read index received' (duration: 30.80398ms)","trace[607778183] 'applied index is now lower than readState.Index' (duration: 89.301607ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:31.734428Z","caller":"traceutil/trace.go:171","msg":"trace[2047179157] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"144.980227ms","start":"2026-04-16T22:51:31.589431Z","end":"2026-04-16T22:51:31.734411Z","steps":["trace[2047179157] 'process raft request' (duration: 55.595407ms)","trace[2047179157] 'compare' (duration: 89.190863ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:31.734676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.419487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T22:51:31.734703Z","caller":"traceutil/trace.go:171","msg":"trace[1172013857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4969; }","duration":"120.52026ms","start":"2026-04-16T22:51:31.614175Z","end":"2026-04-16T22:51:31.734696Z","steps":["trace[1172013857] 'agreement among raft nodes before linearized reading' (duration: 120.22232ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:11.010892Z","caller":"traceutil/trace.go:171","msg":"trace[283132965] linearizableReadLoop","detail":"{readStateIndex:5495; appliedIndex:5494; }","duration":"203.85517ms","start":"2026-04-16T22:52:10.806992Z","end":"2026-04-16T22:52:11.010847Z","steps":["trace[283132965] 'read index received' (duration: 167.517953ms)","trace[283132965] 'applied index is now lower than readState.Index' (duration: 36.336197ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:11.011018Z","caller":"traceutil/trace.go:171","msg":"trace[854270150] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"242.16663ms","start":"2026-04-16T22:52:10.768838Z","end":"2026-04-16T22:52:11.011005Z","steps":["trace[854270150] 'process raft request' (duration: 205.655966ms)","trace[854270150] 'compare' (duration: 36.255905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:11.011297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.289987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-16T22:52:11.011416Z","caller":"traceutil/trace.go:171","msg":"trace[546809156] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5297; }","duration":"204.38444ms","start":"2026-04-16T22:52:10.806956Z","end":"2026-04-16T22:52:11.011341Z","steps":["trace[546809156] 'agreement among raft nodes before linearized reading' (duration: 204.232725ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:11.021201Z","caller":"traceutil/trace.go:171","msg":"trace[552171930] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"208.883216ms","start":"2026-04-16T22:52:10.812306Z","end":"2026-04-16T22:52:11.021189Z","steps":["trace[552171930] 'process raft request' (duration: 208.821673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:11.021415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.364225ms","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-04-16T22:52:11.02148Z","caller":"traceutil/trace.go:171","msg":"trace[1296148229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5298; }","duration":"212.474579ms","start":"2026-04-16T22:52:10.808993Z","end":"2026-04-16T22:52:11.021467Z","steps":["trace[1296148229] 'agreement among raft nodes before linearized reading' (duration: 212.24454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:11.021418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.832463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T22:52:11.021595Z","caller":"traceutil/trace.go:171","msg":"trace[1586768232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5298; }","duration":"194.03414ms","start":"2026-04-16T22:52:10.827548Z","end":"2026-04-16T22:52:11.021582Z","steps":["trace[1586768232] 'agreement among raft nodes before linearized reading' (duration: 193.812622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:11.02271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.231565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-16T22:52:11.022793Z","caller":"traceutil/trace.go:171","msg":"trace[1427121144] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5298; }","duration":"201.338869ms","start":"2026-04-16T22:52:10.821439Z","end":"2026-04-16T22:52:11.022778Z","steps":["trace[1427121144] 'agreement among raft nodes before linearized reading' (duration: 199.935326ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:11.898714Z","caller":"traceutil/trace.go:171","msg":"trace[1287087858] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"153.256498ms","start":"2026-04-16T22:52:11.745391Z","end":"2026-04-16T22:52:11.898647Z","steps":["trace[1287087858] 'process raft request' (duration: 89.184616ms)","trace[1287087858] 'compare' (duration: 63.861704ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:49.009554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2916} {"level":"info","ts":"2026-04-16T22:52:49.095867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2916,"took":"84.708654ms","hash":3487494446,"current-db-size-bytes":54104064,"current-db-size":"54 MB","current-db-size-in-use-bytes":29052928,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-16T22:52:49.09594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3487494446,"revision":2916,"compact-revision":-1} {"level":"info","ts":"2026-04-16T22:53:23.519688Z","caller":"traceutil/trace.go:171","msg":"trace[136181459] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"106.773401ms","start":"2026-04-16T22:53:23.412891Z","end":"2026-04-16T22:53:23.519665Z","steps":["trace[136181459] 'process raft request' (duration: 99.543739ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:00.171052Z","caller":"traceutil/trace.go:171","msg":"trace[330630657] transaction","detail":"{read_only:false; response_revision:6491; number_of_response:1; }","duration":"125.851832ms","start":"2026-04-16T22:55:00.045178Z","end":"2026-04-16T22:55:00.17103Z","steps":["trace[330630657] 'process raft request' (duration: 125.726677ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:00.171577Z","caller":"traceutil/trace.go:171","msg":"trace[1867612833] transaction","detail":"{read_only:false; response_revision:6492; number_of_response:1; }","duration":"124.650789ms","start":"2026-04-16T22:55:00.0469Z","end":"2026-04-16T22:55:00.171551Z","steps":["trace[1867612833] 'process raft request' (duration: 124.509593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:00.453998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.01808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:55:00.454024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.578973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T22:55:00.454071Z","caller":"traceutil/trace.go:171","msg":"trace[1206545952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6492; }","duration":"193.145656ms","start":"2026-04-16T22:55:00.260908Z","end":"2026-04-16T22:55:00.454054Z","steps":["trace[1206545952] 'range keys from in-memory index tree' (duration: 192.940227ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:00.454085Z","caller":"traceutil/trace.go:171","msg":"trace[1069420285] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6492; }","duration":"166.674577ms","start":"2026-04-16T22:55:00.287396Z","end":"2026-04-16T22:55:00.45407Z","steps":["trace[1069420285] 'range keys from in-memory index tree' (duration: 166.449877ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:00.576926Z","caller":"traceutil/trace.go:171","msg":"trace[667309731] transaction","detail":"{read_only:false; response_revision:6493; number_of_response:1; }","duration":"172.99848ms","start":"2026-04-16T22:55:00.403901Z","end":"2026-04-16T22:55:00.5769Z","steps":["trace[667309731] 'process raft request' (duration: 172.799711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:00.577132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.211156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:55:00.57715Z","caller":"traceutil/trace.go:171","msg":"trace[659574105] linearizableReadLoop","detail":"{readStateIndex:6732; appliedIndex:6731; }","duration":"121.961825ms","start":"2026-04-16T22:55:00.454894Z","end":"2026-04-16T22:55:00.576856Z","steps":["trace[659574105] 'read index received' (duration: 121.732255ms)","trace[659574105] 'applied index is now lower than readState.Index' (duration: 228.14µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:00.577201Z","caller":"traceutil/trace.go:171","msg":"trace[756509768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6493; }","duration":"122.343132ms","start":"2026-04-16T22:55:00.454838Z","end":"2026-04-16T22:55:00.577182Z","steps":["trace[756509768] 'agreement among raft nodes before linearized reading' (duration: 122.200786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:00.577536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.505232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T22:55:00.57758Z","caller":"traceutil/trace.go:171","msg":"trace[486106879] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6493; }","duration":"120.768263ms","start":"2026-04-16T22:55:00.456802Z","end":"2026-04-16T22:55:00.57757Z","steps":["trace[486106879] 'agreement among raft nodes before linearized reading' (duration: 120.419829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:00.792511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.795768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:55:00.792593Z","caller":"traceutil/trace.go:171","msg":"trace[736362840] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6493; }","duration":"118.922583ms","start":"2026-04-16T22:55:00.67365Z","end":"2026-04-16T22:55:00.792572Z","steps":["trace[736362840] 'range keys from in-memory index tree' (duration: 118.723455ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:00.92441Z","caller":"traceutil/trace.go:171","msg":"trace[1978508946] linearizableReadLoop","detail":"{readStateIndex:6733; appliedIndex:6732; }","duration":"126.131054ms","start":"2026-04-16T22:55:00.798261Z","end":"2026-04-16T22:55:00.924392Z","steps":["trace[1978508946] 'read index received' (duration: 125.986257ms)","trace[1978508946] 'applied index is now lower than readState.Index' (duration: 144.077µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:00.924495Z","caller":"traceutil/trace.go:171","msg":"trace[564500103] transaction","detail":"{read_only:false; response_revision:6494; number_of_response:1; }","duration":"128.416513ms","start":"2026-04-16T22:55:00.796072Z","end":"2026-04-16T22:55:00.924488Z","steps":["trace[564500103] 'process raft request' (duration: 128.222564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:00.924882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.608934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-16T22:55:00.924924Z","caller":"traceutil/trace.go:171","msg":"trace[347193823] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6494; }","duration":"126.684668ms","start":"2026-04-16T22:55:00.798227Z","end":"2026-04-16T22:55:00.924912Z","steps":["trace[347193823] 'agreement among raft nodes before linearized reading' (duration: 126.566553ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:08.440996Z","caller":"traceutil/trace.go:171","msg":"trace[955812042] linearizableReadLoop","detail":"{readStateIndex:6778; appliedIndex:6776; }","duration":"206.242366ms","start":"2026-04-16T22:55:08.23473Z","end":"2026-04-16T22:55:08.440973Z","steps":["trace[955812042] 'read index received' (duration: 54.04087ms)","trace[955812042] 'applied index is now lower than readState.Index' (duration: 152.200436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:55:08.441319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.57123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:55:08.441366Z","caller":"traceutil/trace.go:171","msg":"trace[744973593] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:6539; }","duration":"206.656285ms","start":"2026-04-16T22:55:08.234697Z","end":"2026-04-16T22:55:08.441354Z","steps":["trace[744973593] 'agreement among raft nodes before linearized reading' (duration: 206.557901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:55:08.441573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.714522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:55:08.441612Z","caller":"traceutil/trace.go:171","msg":"trace[119079572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6539; }","duration":"179.772865ms","start":"2026-04-16T22:55:08.26183Z","end":"2026-04-16T22:55:08.441603Z","steps":["trace[119079572] 'agreement among raft nodes before linearized reading' (duration: 179.720163ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:08.441924Z","caller":"traceutil/trace.go:171","msg":"trace[1365916367] transaction","detail":"{read_only:false; response_revision:6537; number_of_response:1; }","duration":"234.606509ms","start":"2026-04-16T22:55:08.207307Z","end":"2026-04-16T22:55:08.441913Z","steps":["trace[1365916367] 'process raft request' (duration: 226.229028ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:08.441995Z","caller":"traceutil/trace.go:171","msg":"trace[482231119] transaction","detail":"{read_only:false; response_revision:6538; number_of_response:1; }","duration":"200.500049ms","start":"2026-04-16T22:55:08.241427Z","end":"2026-04-16T22:55:08.441927Z","steps":["trace[482231119] 'process raft request' (duration: 199.332159ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:08.441222Z","caller":"traceutil/trace.go:171","msg":"trace[582135590] transaction","detail":"{read_only:false; response_revision:6539; number_of_response:1; }","duration":"186.777347ms","start":"2026-04-16T22:55:08.254422Z","end":"2026-04-16T22:55:08.4412Z","steps":["trace[582135590] 'process raft request' (duration: 186.505965ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:11.165028Z","caller":"traceutil/trace.go:171","msg":"trace[992059636] transaction","detail":"{read_only:false; response_revision:6550; number_of_response:1; }","duration":"117.083555ms","start":"2026-04-16T22:55:11.047908Z","end":"2026-04-16T22:55:11.164991Z","steps":["trace[992059636] 'process raft request' (duration: 116.799953ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:49.014565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5579} {"level":"info","ts":"2026-04-16T22:57:49.08968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5579,"took":"73.882002ms","hash":36374698,"current-db-size-bytes":54104064,"current-db-size":"54 MB","current-db-size-in-use-bytes":18354176,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-16T22:57:49.089732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":36374698,"revision":5579,"compact-revision":2916}