{"level":"warn","ts":"2026-04-20T14:19:03.419375Z","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-20T14:19:03.419502Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.122:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.122:2380","--initial-cluster=instance=https://199.19.213.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.122: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-20T14:19:03.419611Z","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-20T14:19:03.419634Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-04-20T14:19:03.419667Z","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-20T14:19:03.420942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"]} {"level":"info","ts":"2026-04-20T14:19:03.421084Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.122: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-20T14:19:03.429051Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.478828ms"} {"level":"info","ts":"2026-04-20T14:19:03.435657Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f9ba8c11acfbc749","cluster-id":"ca7c3e103d4cd533"} {"level":"info","ts":"2026-04-20T14:19:03.435747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:19:03.435768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:19:03.435778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f9ba8c11acfbc749 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:19:03.43579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:19:03.435829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"warn","ts":"2026-04-20T14:19:03.450822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:19:03.452539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:19:03.455873Z","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-20T14:19:03.458737Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f9ba8c11acfbc749","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:19:03.459363Z","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-20T14:19:03.459465Z","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-20T14:19:03.459474Z","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-20T14:19:03.459486Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f9ba8c11acfbc749","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:19:03.46291Z","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-20T14:19:03.463182Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f9ba8c11acfbc749","initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:19:03.463228Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:19:03.46388Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-04-20T14:19:03.463912Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-04-20T14:19:03.467756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"info","ts":"2026-04-20T14:19:03.467987Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","added-peer-id":"f9ba8c11acfbc749","added-peer-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-04-20T14:19:03.737391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:19:03.737484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:19:03.737519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgPreVoteResp from f9ba8c11acfbc749 at term 1"} {"level":"info","ts":"2026-04-20T14:19:03.737539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:19:03.737554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgVoteResp from f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-04-20T14:19:03.737579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:19:03.737597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f9ba8c11acfbc749 elected leader f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-04-20T14:19:03.745462Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f9ba8c11acfbc749","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.122:2379]}","request-path":"/0/members/f9ba8c11acfbc749/attributes","cluster-id":"ca7c3e103d4cd533","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:19:03.7456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:19:03.745653Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:19:03.745705Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:19:03.745762Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:19:03.746018Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:03.747222Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:19:03.749607Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.122:2379"} {"level":"info","ts":"2026-04-20T14:19:03.751768Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:03.751991Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:03.752182Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:19:31.309479Z","caller":"traceutil/trace.go:171","msg":"trace[646020288] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"107.168027ms","start":"2026-04-20T14:19:31.202285Z","end":"2026-04-20T14:19:31.309453Z","steps":["trace[646020288] 'process raft request' (duration: 70.622857ms)","trace[646020288] 'compare' (duration: 36.348946ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:31.312138Z","caller":"traceutil/trace.go:171","msg":"trace[705076954] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"105.042488ms","start":"2026-04-20T14:19:31.207073Z","end":"2026-04-20T14:19:31.312115Z","steps":["trace[705076954] 'process raft request' (duration: 104.999137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:31.312368Z","caller":"traceutil/trace.go:171","msg":"trace[2036690354] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"109.01089ms","start":"2026-04-20T14:19:31.20335Z","end":"2026-04-20T14:19:31.312361Z","steps":["trace[2036690354] 'process raft request' (duration: 108.626054ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:36.993637Z","caller":"traceutil/trace.go:171","msg":"trace[217568721] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"221.754532ms","start":"2026-04-20T14:19:36.771855Z","end":"2026-04-20T14:19:36.99361Z","steps":["trace[217568721] 'read index received' (duration: 221.60421ms)","trace[217568721] 'applied index is now lower than readState.Index' (duration: 149.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:36.993659Z","caller":"traceutil/trace.go:171","msg":"trace[1036208727] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"375.380288ms","start":"2026-04-20T14:19:36.618254Z","end":"2026-04-20T14:19:36.993634Z","steps":["trace[1036208727] 'process raft request' (duration: 375.221306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:36.993793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.909394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:19:36.993844Z","caller":"traceutil/trace.go:171","msg":"trace[1967222206] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:481; }","duration":"222.052886ms","start":"2026-04-20T14:19:36.771785Z","end":"2026-04-20T14:19:36.993838Z","steps":["trace[1967222206] 'agreement among raft nodes before linearized reading' (duration: 221.917554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:36.994471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:36.618217Z","time spent":"375.5736ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:42.682267Z","caller":"traceutil/trace.go:171","msg":"trace[731580001] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"190.666865ms","start":"2026-04-20T14:19:42.491565Z","end":"2026-04-20T14:19:42.682232Z","steps":["trace[731580001] 'process raft request' (duration: 187.688035ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:44.718222Z","caller":"traceutil/trace.go:171","msg":"trace[224337466] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"342.791188ms","start":"2026-04-20T14:19:44.37541Z","end":"2026-04-20T14:19:44.718201Z","steps":["trace[224337466] 'process raft request' (duration: 342.693086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.718346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.375384Z","time spent":"342.911911ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.72755Z","caller":"traceutil/trace.go:171","msg":"trace[795867331] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"337.936185ms","start":"2026-04-20T14:19:44.389598Z","end":"2026-04-20T14:19:44.727534Z","steps":["trace[795867331] 'process raft request' (duration: 337.318701ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:44.72758Z","caller":"traceutil/trace.go:171","msg":"trace[1829389995] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"334.132427ms","start":"2026-04-20T14:19:44.39343Z","end":"2026-04-20T14:19:44.727563Z","steps":["trace[1829389995] 'process raft request' (duration: 333.570974ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:44.72761Z","caller":"traceutil/trace.go:171","msg":"trace[820883561] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"330.01819ms","start":"2026-04-20T14:19:44.397578Z","end":"2026-04-20T14:19:44.727596Z","steps":["trace[820883561] 'process raft request' (duration: 329.466998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.72764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.389579Z","time spent":"338.017086ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:44.727671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.397568Z","time spent":"330.074862ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.727704Z","caller":"traceutil/trace.go:171","msg":"trace[1895540407] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"336.205545ms","start":"2026-04-20T14:19:44.391492Z","end":"2026-04-20T14:19:44.727697Z","steps":["trace[1895540407] 'process raft request' (duration: 335.457708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.727738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.391479Z","time spent":"336.245596ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:44.727673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.393422Z","time spent":"334.198548ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.727876Z","caller":"traceutil/trace.go:171","msg":"trace[552603362] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"346.233528ms","start":"2026-04-20T14:19:44.381584Z","end":"2026-04-20T14:19:44.727818Z","steps":["trace[552603362] 'process raft request' (duration: 345.247435ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:44.727952Z","caller":"traceutil/trace.go:171","msg":"trace[919585144] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"327.796669ms","start":"2026-04-20T14:19:44.400142Z","end":"2026-04-20T14:19:44.727939Z","steps":["trace[919585144] 'process raft request' (duration: 326.98175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.728014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.400135Z","time spent":"327.8521ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.728077Z","caller":"traceutil/trace.go:171","msg":"trace[726595473] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"346.410263ms","start":"2026-04-20T14:19:44.381646Z","end":"2026-04-20T14:19:44.728056Z","steps":["trace[726595473] 'process raft request' (duration: 345.246525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.728136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.381627Z","time spent":"346.482784ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:44.728095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.381569Z","time spent":"346.392903ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.728309Z","caller":"traceutil/trace.go:171","msg":"trace[841966251] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"318.603156ms","start":"2026-04-20T14:19:44.409696Z","end":"2026-04-20T14:19:44.728299Z","steps":["trace[841966251] 'process raft request' (duration: 317.501689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.728362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.409686Z","time spent":"318.656827ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.728563Z","caller":"traceutil/trace.go:171","msg":"trace[1965302743] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"318.80248ms","start":"2026-04-20T14:19:44.409747Z","end":"2026-04-20T14:19:44.728549Z","steps":["trace[1965302743] 'process raft request' (duration: 318.621516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:44.728621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:44.409743Z","time spent":"318.854611ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:19:44.728845Z","caller":"traceutil/trace.go:171","msg":"trace[791690325] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"291.754561ms","start":"2026-04-20T14:19:44.437071Z","end":"2026-04-20T14:19:44.728826Z","steps":["trace[791690325] 'process raft request' (duration: 291.438543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:44.728894Z","caller":"traceutil/trace.go:171","msg":"trace[1995231583] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"164.495409ms","start":"2026-04-20T14:19:44.564387Z","end":"2026-04-20T14:19:44.728882Z","steps":["trace[1995231583] 'process raft request' (duration: 164.239543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:48.340672Z","caller":"traceutil/trace.go:171","msg":"trace[1864768071] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"156.834944ms","start":"2026-04-20T14:19:48.18381Z","end":"2026-04-20T14:19:48.340645Z","steps":["trace[1864768071] 'process raft request' (duration: 146.519931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:51.565091Z","caller":"traceutil/trace.go:171","msg":"trace[77821282] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"269.536923ms","start":"2026-04-20T14:19:51.295531Z","end":"2026-04-20T14:19:51.565068Z","steps":["trace[77821282] 'process raft request' (duration: 267.989669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:52.004263Z","caller":"traceutil/trace.go:171","msg":"trace[1796731787] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"323.937676ms","start":"2026-04-20T14:19:51.680301Z","end":"2026-04-20T14:19:52.004239Z","steps":["trace[1796731787] 'read index received' (duration: 323.741501ms)","trace[1796731787] 'applied index is now lower than readState.Index' (duration: 193.435µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:52.004314Z","caller":"traceutil/trace.go:171","msg":"trace[1793289268] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"371.161211ms","start":"2026-04-20T14:19:51.633123Z","end":"2026-04-20T14:19:52.004284Z","steps":["trace[1793289268] 'process raft request' (duration: 370.972796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:52.004432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:51.633097Z","time spent":"371.280154ms","remote":"127.0.0.1:60362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:19:52.004436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.11292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:52.004475Z","caller":"traceutil/trace.go:171","msg":"trace[1925194031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"324.298073ms","start":"2026-04-20T14:19:51.680164Z","end":"2026-04-20T14:19:52.004462Z","steps":["trace[1925194031] 'agreement among raft nodes before linearized reading' (duration: 324.202001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:52.004509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:51.680149Z","time spent":"324.352595ms","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:19:52.004512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.765205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fr5dv\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-20T14:19:52.005065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.779547ms","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-20T14:19:52.005086Z","caller":"traceutil/trace.go:171","msg":"trace[1097455625] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fr5dv; range_end:; response_count:1; response_revision:646; }","duration":"321.328337ms","start":"2026-04-20T14:19:51.683716Z","end":"2026-04-20T14:19:52.005044Z","steps":["trace[1097455625] 'agreement among raft nodes before linearized reading' (duration: 320.686113ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:52.005118Z","caller":"traceutil/trace.go:171","msg":"trace[430810228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"269.858749ms","start":"2026-04-20T14:19:51.735234Z","end":"2026-04-20T14:19:52.005093Z","steps":["trace[430810228] 'agreement among raft nodes before linearized reading' (duration: 269.347228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:52.005161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:51.68371Z","time spent":"321.429049ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-fr5dv\" "} {"level":"warn","ts":"2026-04-20T14:19:53.190641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.992677ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:53.190764Z","caller":"traceutil/trace.go:171","msg":"trace[1405780997] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:648; }","duration":"147.162ms","start":"2026-04-20T14:19:53.043585Z","end":"2026-04-20T14:19:53.190747Z","steps":["trace[1405780997] 'range keys from in-memory index tree' (duration: 146.966166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:53.191414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081918769s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T14:19:53.191697Z","caller":"traceutil/trace.go:171","msg":"trace[137209661] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"1.178105822s","start":"2026-04-20T14:19:52.013577Z","end":"2026-04-20T14:19:53.191683Z","steps":["trace[137209661] 'read index received' (duration: 36.017692ms)","trace[137209661] 'applied index is now lower than readState.Index' (duration: 1.14208719s)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:53.191756Z","caller":"traceutil/trace.go:171","msg":"trace[439122382] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.171771992s","start":"2026-04-20T14:19:52.019958Z","end":"2026-04-20T14:19:53.19173Z","steps":["trace[439122382] 'process raft request' (duration: 1.171573178s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:53.191954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:52.019948Z","time spent":"1.171932745s","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:19:53.191961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178366348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-20T14:19:53.192064Z","caller":"traceutil/trace.go:171","msg":"trace[1142826332] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:649; }","duration":"1.178463409s","start":"2026-04-20T14:19:52.013553Z","end":"2026-04-20T14:19:53.192017Z","steps":["trace[1142826332] 'agreement among raft nodes before linearized reading' (duration: 1.178302966s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:53.192118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:52.013545Z","time spent":"1.178561171s","remote":"127.0.0.1:60324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-20T14:19:53.192533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.7244ms","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-20T14:19:53.192585Z","caller":"traceutil/trace.go:171","msg":"trace[481598609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"129.818902ms","start":"2026-04-20T14:19:53.062752Z","end":"2026-04-20T14:19:53.192571Z","steps":["trace[481598609] 'agreement among raft nodes before linearized reading' (duration: 129.676549ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:53.325914Z","caller":"traceutil/trace.go:171","msg":"trace[1760704383] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:675; }","duration":"126.789107ms","start":"2026-04-20T14:19:53.199104Z","end":"2026-04-20T14:19:53.325893Z","steps":["trace[1760704383] 'read index received' (duration: 44.44851ms)","trace[1760704383] 'applied index is now lower than readState.Index' (duration: 82.339797ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:53.325955Z","caller":"traceutil/trace.go:171","msg":"trace[385986427] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"128.34085ms","start":"2026-04-20T14:19:53.197593Z","end":"2026-04-20T14:19:53.325934Z","steps":["trace[385986427] 'process raft request' (duration: 127.908751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:53.326029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.914069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:19:53.326075Z","caller":"traceutil/trace.go:171","msg":"trace[2074387515] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:651; }","duration":"126.991371ms","start":"2026-04-20T14:19:53.199068Z","end":"2026-04-20T14:19:53.326059Z","steps":["trace[2074387515] 'agreement among raft nodes before linearized reading' (duration: 126.911479ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:55.073136Z","caller":"traceutil/trace.go:171","msg":"trace[1622748997] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"196.892185ms","start":"2026-04-20T14:19:54.876222Z","end":"2026-04-20T14:19:55.073114Z","steps":["trace[1622748997] 'process raft request' (duration: 196.791733ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:08.445423Z","caller":"traceutil/trace.go:171","msg":"trace[1200945420] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"262.907825ms","start":"2026-04-20T14:20:08.182488Z","end":"2026-04-20T14:20:08.445396Z","steps":["trace[1200945420] 'process raft request' (duration: 262.728852ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:08.565259Z","caller":"traceutil/trace.go:171","msg":"trace[649148961] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"379.935133ms","start":"2026-04-20T14:20:08.18527Z","end":"2026-04-20T14:20:08.565205Z","steps":["trace[649148961] 'process raft request' (duration: 379.664898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:08.565507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:08.185247Z","time spent":"380.156367ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:20:15.990677Z","caller":"traceutil/trace.go:171","msg":"trace[230977452] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"165.19292ms","start":"2026-04-20T14:20:15.825454Z","end":"2026-04-20T14:20:15.990647Z","steps":["trace[230977452] 'process raft request' (duration: 165.001977ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:16.09671Z","caller":"traceutil/trace.go:171","msg":"trace[1220883605] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"100.234936ms","start":"2026-04-20T14:20:15.996442Z","end":"2026-04-20T14:20:16.096677Z","steps":["trace[1220883605] 'process raft request' (duration: 100.043802ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:16.222358Z","caller":"traceutil/trace.go:171","msg":"trace[1592769124] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"119.188284ms","start":"2026-04-20T14:20:16.103146Z","end":"2026-04-20T14:20:16.222335Z","steps":["trace[1592769124] 'process raft request' (duration: 119.095532ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:16.222528Z","caller":"traceutil/trace.go:171","msg":"trace[42619642] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"120.442807ms","start":"2026-04-20T14:20:16.102058Z","end":"2026-04-20T14:20:16.222501Z","steps":["trace[42619642] 'process raft request' (duration: 96.566056ms)","trace[42619642] 'compare' (duration: 23.490514ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:16.223127Z","caller":"traceutil/trace.go:171","msg":"trace[411462080] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"111.13805ms","start":"2026-04-20T14:20:16.111966Z","end":"2026-04-20T14:20:16.223104Z","steps":["trace[411462080] 'process raft request' (duration: 111.031758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:37.855213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.605021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:20:37.855562Z","caller":"traceutil/trace.go:171","msg":"trace[2109298841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"175.012658ms","start":"2026-04-20T14:20:37.680528Z","end":"2026-04-20T14:20:37.855541Z","steps":["trace[2109298841] 'range keys from in-memory index tree' (duration: 174.49663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:37.855217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.120538ms","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-20T14:20:37.855732Z","caller":"traceutil/trace.go:171","msg":"trace[2024637397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"300.672997ms","start":"2026-04-20T14:20:37.555027Z","end":"2026-04-20T14:20:37.8557Z","steps":["trace[2024637397] 'range keys from in-memory index tree' (duration: 299.931435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:37.855901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:37.555011Z","time spent":"300.862021ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T14:20:40.992888Z","caller":"traceutil/trace.go:171","msg":"trace[1336398895] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"145.909302ms","start":"2026-04-20T14:20:40.846935Z","end":"2026-04-20T14:20:40.992844Z","steps":["trace[1336398895] 'process raft request' (duration: 114.823309ms)","trace[1336398895] 'compare' (duration: 30.807198ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:51.81729Z","caller":"traceutil/trace.go:171","msg":"trace[775383903] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"136.691476ms","start":"2026-04-20T14:20:51.680575Z","end":"2026-04-20T14:20:51.817267Z","steps":["trace[775383903] 'read index received' (duration: 135.903484ms)","trace[775383903] 'applied index is now lower than readState.Index' (duration: 782.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:51.817426Z","caller":"traceutil/trace.go:171","msg":"trace[1232813702] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"218.640534ms","start":"2026-04-20T14:20:51.598726Z","end":"2026-04-20T14:20:51.817366Z","steps":["trace[1232813702] 'process raft request' (duration: 217.708129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:51.817441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.850518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:20:51.81748Z","caller":"traceutil/trace.go:171","msg":"trace[974231741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"136.94186ms","start":"2026-04-20T14:20:51.680528Z","end":"2026-04-20T14:20:51.81747Z","steps":["trace[974231741] 'agreement among raft nodes before linearized reading' (duration: 136.859629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:57.265658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.503199ms","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-20T14:20:57.26573Z","caller":"traceutil/trace.go:171","msg":"trace[1121231196] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1456; }","duration":"112.643752ms","start":"2026-04-20T14:20:57.153073Z","end":"2026-04-20T14:20:57.265717Z","steps":["trace[1121231196] 'count revisions from in-memory index tree' (duration: 112.379417ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:07.139792Z","caller":"traceutil/trace.go:171","msg":"trace[168007774] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"358.511226ms","start":"2026-04-20T14:21:06.781257Z","end":"2026-04-20T14:21:07.139768Z","steps":["trace[168007774] 'process raft request' (duration: 298.673196ms)","trace[168007774] 'compare' (duration: 59.706858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:07.139915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:06.781214Z","time spent":"358.640638ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:07.169724Z","caller":"traceutil/trace.go:171","msg":"trace[1907925921] linearizableReadLoop","detail":"{readStateIndex:1610; appliedIndex:1608; }","duration":"173.46227ms","start":"2026-04-20T14:21:06.996239Z","end":"2026-04-20T14:21:07.169701Z","steps":["trace[1907925921] 'read index received' (duration: 83.684204ms)","trace[1907925921] 'applied index is now lower than readState.Index' (duration: 89.776836ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:07.169775Z","caller":"traceutil/trace.go:171","msg":"trace[753123758] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"330.247204ms","start":"2026-04-20T14:21:06.839492Z","end":"2026-04-20T14:21:07.169739Z","steps":["trace[753123758] 'process raft request' (duration: 330.060111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:07.16996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:06.839474Z","time spent":"330.383236ms","remote":"127.0.0.1:60170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:07.170083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.799445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:21:07.170191Z","caller":"traceutil/trace.go:171","msg":"trace[138363567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1562; }","duration":"173.960037ms","start":"2026-04-20T14:21:06.996208Z","end":"2026-04-20T14:21:07.170168Z","steps":["trace[138363567] 'agreement among raft nodes before linearized reading' (duration: 173.556041ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:11.336908Z","caller":"traceutil/trace.go:171","msg":"trace[1245810411] linearizableReadLoop","detail":"{readStateIndex:1739; appliedIndex:1737; }","duration":"143.576198ms","start":"2026-04-20T14:21:11.193305Z","end":"2026-04-20T14:21:11.336881Z","steps":["trace[1245810411] 'read index received' (duration: 2.150973ms)","trace[1245810411] 'applied index is now lower than readState.Index' (duration: 141.423875ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:11.337053Z","caller":"traceutil/trace.go:171","msg":"trace[956610627] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"150.649095ms","start":"2026-04-20T14:21:11.18639Z","end":"2026-04-20T14:21:11.337039Z","steps":["trace[956610627] 'process raft request' (duration: 147.238324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:11.337473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.899402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-20T14:21:11.337503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.152397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T14:21:11.337552Z","caller":"traceutil/trace.go:171","msg":"trace[876982205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1687; }","duration":"136.030136ms","start":"2026-04-20T14:21:11.201504Z","end":"2026-04-20T14:21:11.337534Z","steps":["trace[876982205] 'agreement among raft nodes before linearized reading' (duration: 135.601169ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:11.337606Z","caller":"traceutil/trace.go:171","msg":"trace[298065062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1687; }","duration":"144.3052ms","start":"2026-04-20T14:21:11.193273Z","end":"2026-04-20T14:21:11.337578Z","steps":["trace[298065062] 'agreement among raft nodes before linearized reading' (duration: 143.939503ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:15.688428Z","caller":"traceutil/trace.go:171","msg":"trace[812720825] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"121.436675ms","start":"2026-04-20T14:21:15.566966Z","end":"2026-04-20T14:21:15.688403Z","steps":["trace[812720825] 'process raft request' (duration: 92.091979ms)","trace[812720825] 'compare' (duration: 29.147243ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:15.689427Z","caller":"traceutil/trace.go:171","msg":"trace[2084241756] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"122.214287ms","start":"2026-04-20T14:21:15.567182Z","end":"2026-04-20T14:21:15.689397Z","steps":["trace[2084241756] 'process raft request' (duration: 121.164322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:15.689458Z","caller":"traceutil/trace.go:171","msg":"trace[1436205617] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"122.182876ms","start":"2026-04-20T14:21:15.56726Z","end":"2026-04-20T14:21:15.689443Z","steps":["trace[1436205617] 'process raft request' (duration: 121.995144ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:15.689723Z","caller":"traceutil/trace.go:171","msg":"trace[1326702434] linearizableReadLoop","detail":"{readStateIndex:1798; appliedIndex:1792; }","duration":"105.855933ms","start":"2026-04-20T14:21:15.583847Z","end":"2026-04-20T14:21:15.689703Z","steps":["trace[1326702434] 'read index received' (duration: 24.20063ms)","trace[1326702434] 'applied index is now lower than readState.Index' (duration: 81.654533ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:15.689831Z","caller":"traceutil/trace.go:171","msg":"trace[719267705] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"122.548472ms","start":"2026-04-20T14:21:15.567272Z","end":"2026-04-20T14:21:15.68982Z","steps":["trace[719267705] 'process raft request' (duration: 122.105135ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:15.689896Z","caller":"traceutil/trace.go:171","msg":"trace[1275416473] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"121.220762ms","start":"2026-04-20T14:21:15.56866Z","end":"2026-04-20T14:21:15.689881Z","steps":["trace[1275416473] 'process raft request' (duration: 120.998349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:15.690109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.24151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:15.690154Z","caller":"traceutil/trace.go:171","msg":"trace[999835799] range","detail":"{range_begin:/registry/secrets/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1744; }","duration":"106.324351ms","start":"2026-04-20T14:21:15.583819Z","end":"2026-04-20T14:21:15.690143Z","steps":["trace[999835799] 'agreement among raft nodes before linearized reading' (duration: 106.218809ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:15.690488Z","caller":"traceutil/trace.go:171","msg":"trace[1795315348] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"123.15751ms","start":"2026-04-20T14:21:15.567317Z","end":"2026-04-20T14:21:15.690474Z","steps":["trace[1795315348] 'process raft request' (duration: 122.182326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:21.508318Z","caller":"traceutil/trace.go:171","msg":"trace[1556154903] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"272.873651ms","start":"2026-04-20T14:21:21.235419Z","end":"2026-04-20T14:21:21.508292Z","steps":["trace[1556154903] 'read index received' (duration: 272.728119ms)","trace[1556154903] 'applied index is now lower than readState.Index' (duration: 144.462µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:21.50837Z","caller":"traceutil/trace.go:171","msg":"trace[1038118984] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"381.937446ms","start":"2026-04-20T14:21:21.126394Z","end":"2026-04-20T14:21:21.508331Z","steps":["trace[1038118984] 'process raft request' (duration: 381.712943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:21.50873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:21.126382Z","time spent":"382.072348ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:21.509112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.680292ms","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-20T14:21:21.509216Z","caller":"traceutil/trace.go:171","msg":"trace[1032672167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1851; }","duration":"273.841635ms","start":"2026-04-20T14:21:21.235354Z","end":"2026-04-20T14:21:21.509196Z","steps":["trace[1032672167] 'agreement among raft nodes before linearized reading' (duration: 273.031533ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:21.531513Z","caller":"traceutil/trace.go:171","msg":"trace[1633859828] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"153.962752ms","start":"2026-04-20T14:21:21.377526Z","end":"2026-04-20T14:21:21.531489Z","steps":["trace[1633859828] 'process raft request' (duration: 153.784729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:21.53158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.368783ms","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-20T14:21:21.531643Z","caller":"traceutil/trace.go:171","msg":"trace[667510160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"127.511845ms","start":"2026-04-20T14:21:21.404117Z","end":"2026-04-20T14:21:21.531629Z","steps":["trace[667510160] 'agreement among raft nodes before linearized reading' (duration: 127.316602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:21.531776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.034749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:21:21.531946Z","caller":"traceutil/trace.go:171","msg":"trace[1461629225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"125.223152ms","start":"2026-04-20T14:21:21.406698Z","end":"2026-04-20T14:21:21.531921Z","steps":["trace[1461629225] 'agreement among raft nodes before linearized reading' (duration: 124.838865ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:22.006774Z","caller":"traceutil/trace.go:171","msg":"trace[1646654164] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"492.25187ms","start":"2026-04-20T14:21:21.514452Z","end":"2026-04-20T14:21:22.006704Z","steps":["trace[1646654164] 'process raft request' (duration: 476.619212ms)","trace[1646654164] 'compare' (duration: 15.247292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:22.007368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:21.514427Z","time spent":"492.453173ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:22.010423Z","caller":"traceutil/trace.go:171","msg":"trace[182919004] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"474.113875ms","start":"2026-04-20T14:21:21.536275Z","end":"2026-04-20T14:21:22.010389Z","steps":["trace[182919004] 'process raft request' (duration: 473.869321ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:22.010595Z","caller":"traceutil/trace.go:171","msg":"trace[261480926] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1908; }","duration":"443.191752ms","start":"2026-04-20T14:21:21.567382Z","end":"2026-04-20T14:21:22.010573Z","steps":["trace[261480926] 'read index received' (duration: 423.706728ms)","trace[261480926] 'applied index is now lower than readState.Index' (duration: 19.482944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:22.010655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:21.536259Z","time spent":"474.268377ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:22.010634Z","caller":"traceutil/trace.go:171","msg":"trace[1651277117] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"473.081038ms","start":"2026-04-20T14:21:21.537507Z","end":"2026-04-20T14:21:22.010588Z","steps":["trace[1651277117] 'process raft request' (duration: 472.826775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:22.010877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:21.537499Z","time spent":"473.235841ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:22.010975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.10316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T14:21:22.011066Z","caller":"traceutil/trace.go:171","msg":"trace[1826868278] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1855; }","duration":"257.253843ms","start":"2026-04-20T14:21:21.753788Z","end":"2026-04-20T14:21:22.011042Z","steps":["trace[1826868278] 'agreement among raft nodes before linearized reading' (duration: 256.973169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:22.011165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.750171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-04-20T14:21:22.011182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.486149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-465013c6-b6c7-4b26-baba-a7a192e0d698\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-04-20T14:21:22.01123Z","caller":"traceutil/trace.go:171","msg":"trace[1903759156] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-465013c6-b6c7-4b26-baba-a7a192e0d698; range_end:; response_count:1; response_revision:1855; }","duration":"210.63237ms","start":"2026-04-20T14:21:21.800583Z","end":"2026-04-20T14:21:22.011215Z","steps":["trace[1903759156] 'agreement among raft nodes before linearized reading' (duration: 210.457788ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:22.01124Z","caller":"traceutil/trace.go:171","msg":"trace[1921526755] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1855; }","duration":"443.856612ms","start":"2026-04-20T14:21:21.567348Z","end":"2026-04-20T14:21:22.011205Z","steps":["trace[1921526755] 'agreement among raft nodes before linearized reading' (duration: 443.667249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:22.011351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:21.567336Z","time spent":"443.933684ms","remote":"127.0.0.1:38070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T14:21:22.222662Z","caller":"traceutil/trace.go:171","msg":"trace[1300466137] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"156.566954ms","start":"2026-04-20T14:21:22.066065Z","end":"2026-04-20T14:21:22.222631Z","steps":["trace[1300466137] 'process raft request' (duration: 108.500743ms)","trace[1300466137] 'compare' (duration: 47.921549ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:49.180166Z","caller":"traceutil/trace.go:171","msg":"trace[873954913] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"145.761778ms","start":"2026-04-20T14:21:49.034373Z","end":"2026-04-20T14:21:49.180135Z","steps":["trace[873954913] 'process raft request' (duration: 145.575565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.249823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.131916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:21:55.249999Z","caller":"traceutil/trace.go:171","msg":"trace[2139398309] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"242.917962ms","start":"2026-04-20T14:21:55.007062Z","end":"2026-04-20T14:21:55.24998Z","steps":["trace[2139398309] 'process raft request' (duration: 242.858861ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:55.250317Z","caller":"traceutil/trace.go:171","msg":"trace[766716911] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"651.029984ms","start":"2026-04-20T14:21:54.599272Z","end":"2026-04-20T14:21:55.250302Z","steps":["trace[766716911] 'process raft request' (duration: 92.338741ms)","trace[766716911] 'compare' (duration: 557.750631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:55.250379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:54.599251Z","time spent":"651.093645ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:55.250442Z","caller":"traceutil/trace.go:171","msg":"trace[1826890331] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"594.032976ms","start":"2026-04-20T14:21:54.656373Z","end":"2026-04-20T14:21:55.250406Z","steps":["trace[1826890331] 'read index received' (duration: 35.234641ms)","trace[1826890331] 'applied index is now lower than readState.Index' (duration: 558.793135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:55.250667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.282269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:55.25071Z","caller":"traceutil/trace.go:171","msg":"trace[1417699083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2128; }","duration":"594.33885ms","start":"2026-04-20T14:21:54.656358Z","end":"2026-04-20T14:21:55.250697Z","steps":["trace[1417699083] 'agreement among raft nodes before linearized reading' (duration: 594.246788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.250741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:54.656299Z","time spent":"594.434671ms","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:21:55.250821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.247354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-20T14:21:55.250902Z","caller":"traceutil/trace.go:171","msg":"trace[960292937] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2128; }","duration":"352.370455ms","start":"2026-04-20T14:21:54.89851Z","end":"2026-04-20T14:21:55.25088Z","steps":["trace[960292937] 'agreement among raft nodes before linearized reading' (duration: 352.160842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.250952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:54.898489Z","time spent":"352.451418ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:21:55.250873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.730127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11667"} {"level":"info","ts":"2026-04-20T14:21:55.25147Z","caller":"traceutil/trace.go:171","msg":"trace[1185574034] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2128; }","duration":"568.377155ms","start":"2026-04-20T14:21:54.683075Z","end":"2026-04-20T14:21:55.251452Z","steps":["trace[1185574034] 'agreement among raft nodes before linearized reading' (duration: 567.612114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.251533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:54.683054Z","time spent":"568.448977ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11691,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T14:21:55.251851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.813417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T14:21:55.251913Z","caller":"traceutil/trace.go:171","msg":"trace[1038505500] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2128; }","duration":"386.919267ms","start":"2026-04-20T14:21:54.864978Z","end":"2026-04-20T14:21:55.251898Z","steps":["trace[1038505500] 'agreement among raft nodes before linearized reading' (duration: 385.68927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.251947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:54.864955Z","time spent":"386.983919ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:21:55.252501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.997915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:55.252932Z","caller":"traceutil/trace.go:171","msg":"trace[1827281541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2128; }","duration":"572.483822ms","start":"2026-04-20T14:21:54.680422Z","end":"2026-04-20T14:21:55.252906Z","steps":["trace[1827281541] 'agreement among raft nodes before linearized reading' (duration: 570.483584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.253545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:54.680403Z","time spent":"573.12545ms","remote":"127.0.0.1:60158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:21:55.614656Z","caller":"traceutil/trace.go:171","msg":"trace[1001919643] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"355.609548ms","start":"2026-04-20T14:21:55.25902Z","end":"2026-04-20T14:21:55.614629Z","steps":["trace[1001919643] 'process raft request' (duration: 313.581135ms)","trace[1001919643] 'compare' (duration: 41.82981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:55.614867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.259003Z","time spent":"355.736159ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:55.624824Z","caller":"traceutil/trace.go:171","msg":"trace[2098079845] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2198; }","duration":"322.741739ms","start":"2026-04-20T14:21:55.302059Z","end":"2026-04-20T14:21:55.624801Z","steps":["trace[2098079845] 'read index received' (duration: 270.501046ms)","trace[2098079845] 'applied index is now lower than readState.Index' (duration: 52.239733ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:55.624904Z","caller":"traceutil/trace.go:171","msg":"trace[1254348247] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"332.039367ms","start":"2026-04-20T14:21:55.292849Z","end":"2026-04-20T14:21:55.624888Z","steps":["trace[1254348247] 'process raft request' (duration: 331.887695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.625147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.080803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-20T14:21:55.625181Z","caller":"traceutil/trace.go:171","msg":"trace[1711429885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"323.143275ms","start":"2026-04-20T14:21:55.30203Z","end":"2026-04-20T14:21:55.625173Z","steps":["trace[1711429885] 'agreement among raft nodes before linearized reading' (duration: 322.937892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.625211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.302017Z","time spent":"323.180705ms","remote":"127.0.0.1:38070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T14:21:55.62547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.292826Z","time spent":"332.27637ms","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16383,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:55.624947Z","caller":"traceutil/trace.go:171","msg":"trace[1929826977] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"365.665055ms","start":"2026-04-20T14:21:55.259274Z","end":"2026-04-20T14:21:55.624939Z","steps":["trace[1929826977] 'process raft request' (duration: 365.29877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.625993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.25926Z","time spent":"366.653369ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:55.929736Z","caller":"traceutil/trace.go:171","msg":"trace[13746707] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"300.190304ms","start":"2026-04-20T14:21:55.629512Z","end":"2026-04-20T14:21:55.929702Z","steps":["trace[13746707] 'process raft request' (duration: 299.869468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.929908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.629498Z","time spent":"300.328425ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:21:55.935239Z","caller":"traceutil/trace.go:171","msg":"trace[1027416428] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2132; }","duration":"303.525619ms","start":"2026-04-20T14:21:55.63169Z","end":"2026-04-20T14:21:55.935215Z","steps":["trace[1027416428] 'process raft request' (duration: 303.241464ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:55.935286Z","caller":"traceutil/trace.go:171","msg":"trace[104146008] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"270.906244ms","start":"2026-04-20T14:21:55.66436Z","end":"2026-04-20T14:21:55.935266Z","steps":["trace[104146008] 'process raft request' (duration: 270.785732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.935367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.631667Z","time spent":"303.64396ms","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:21:55.935251Z","caller":"traceutil/trace.go:171","msg":"trace[564602548] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"303.106493ms","start":"2026-04-20T14:21:55.632126Z","end":"2026-04-20T14:21:55.935233Z","steps":["trace[564602548] 'process raft request' (duration: 302.949531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:55.935669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:55.6321Z","time spent":"303.438947ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:21:55.935914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.155923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:55.935968Z","caller":"traceutil/trace.go:171","msg":"trace[2026412764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2134; }","duration":"256.236294ms","start":"2026-04-20T14:21:55.679719Z","end":"2026-04-20T14:21:55.935955Z","steps":["trace[2026412764] 'agreement among raft nodes before linearized reading' (duration: 256.147233ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:55.935817Z","caller":"traceutil/trace.go:171","msg":"trace[1127544422] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2202; }","duration":"256.048792ms","start":"2026-04-20T14:21:55.679749Z","end":"2026-04-20T14:21:55.935798Z","steps":["trace[1127544422] 'read index received' (duration: 249.980929ms)","trace[1127544422] 'applied index is now lower than readState.Index' (duration: 6.065723ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:22:00.107773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.92763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:22:00.107881Z","caller":"traceutil/trace.go:171","msg":"trace[36461669] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"128.983736ms","start":"2026-04-20T14:21:59.978878Z","end":"2026-04-20T14:22:00.107862Z","steps":["trace[36461669] 'process raft request' (duration: 20.873063ms)","trace[36461669] 'compare' (duration: 107.742088ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:00.196394Z","caller":"traceutil/trace.go:171","msg":"trace[594108147] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"217.27642ms","start":"2026-04-20T14:21:59.979085Z","end":"2026-04-20T14:22:00.196361Z","steps":["trace[594108147] 'process raft request' (duration: 217.000147ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:46.69358Z","caller":"traceutil/trace.go:171","msg":"trace[921318826] linearizableReadLoop","detail":"{readStateIndex:2672; appliedIndex:2671; }","duration":"128.720546ms","start":"2026-04-20T14:22:46.564843Z","end":"2026-04-20T14:22:46.693564Z","steps":["trace[921318826] 'read index received' (duration: 128.577474ms)","trace[921318826] 'applied index is now lower than readState.Index' (duration: 142.522µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:46.693795Z","caller":"traceutil/trace.go:171","msg":"trace[1884265530] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"545.610035ms","start":"2026-04-20T14:22:46.148178Z","end":"2026-04-20T14:22:46.693788Z","steps":["trace[1884265530] 'process raft request' (duration: 545.296261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:46.693873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:22:46.148153Z","time spent":"545.662457ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:22:46.694095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.251802ms","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-20T14:22:46.694128Z","caller":"traceutil/trace.go:171","msg":"trace[135410204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2578; }","duration":"129.320743ms","start":"2026-04-20T14:22:46.564802Z","end":"2026-04-20T14:22:46.694123Z","steps":["trace[135410204] 'agreement among raft nodes before linearized reading' (duration: 129.231531ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.484715Z","caller":"traceutil/trace.go:171","msg":"trace[708127283] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"111.915393ms","start":"2026-04-20T14:23:14.372771Z","end":"2026-04-20T14:23:14.484686Z","steps":["trace[708127283] 'process raft request' (duration: 111.723211ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:14.712367Z","caller":"traceutil/trace.go:171","msg":"trace[2112100448] linearizableReadLoop","detail":"{readStateIndex:2880; appliedIndex:2879; }","duration":"227.722158ms","start":"2026-04-20T14:23:14.484622Z","end":"2026-04-20T14:23:14.712344Z","steps":["trace[2112100448] 'read index received' (duration: 227.629967ms)","trace[2112100448] 'applied index is now lower than readState.Index' (duration: 91.311µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:14.712545Z","caller":"traceutil/trace.go:171","msg":"trace[715607123] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"266.644329ms","start":"2026-04-20T14:23:14.445864Z","end":"2026-04-20T14:23:14.712508Z","steps":["trace[715607123] 'process raft request' (duration: 266.345465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.712751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.100839ms","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-20T14:23:14.7129Z","caller":"traceutil/trace.go:171","msg":"trace[962211807] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2773; }","duration":"314.366081ms","start":"2026-04-20T14:23:14.398508Z","end":"2026-04-20T14:23:14.712874Z","steps":["trace[962211807] 'agreement among raft nodes before linearized reading' (duration: 314.069838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:14.713011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.398491Z","time spent":"314.501873ms","remote":"127.0.0.1:60492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:23:14.753599Z","caller":"traceutil/trace.go:171","msg":"trace[57402899] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"266.457336ms","start":"2026-04-20T14:23:14.487119Z","end":"2026-04-20T14:23:14.753576Z","steps":["trace[57402899] 'process raft request' (duration: 266.274954ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:23:15.163193Z","caller":"traceutil/trace.go:171","msg":"trace[702976546] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2881; }","duration":"338.266653ms","start":"2026-04-20T14:23:14.824905Z","end":"2026-04-20T14:23:15.163171Z","steps":["trace[702976546] 'read index received' (duration: 338.05978ms)","trace[702976546] 'applied index is now lower than readState.Index' (duration: 205.803µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:23:15.163254Z","caller":"traceutil/trace.go:171","msg":"trace[1699950606] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"376.193592ms","start":"2026-04-20T14:23:14.787027Z","end":"2026-04-20T14:23:15.16322Z","steps":["trace[1699950606] 'process raft request' (duration: 376.00801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.163726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.55519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-20T14:23:15.16376Z","caller":"traceutil/trace.go:171","msg":"trace[1364281789] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2775; }","duration":"229.616ms","start":"2026-04-20T14:23:14.934131Z","end":"2026-04-20T14:23:15.163747Z","steps":["trace[1364281789] 'agreement among raft nodes before linearized reading' (duration: 229.545759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.16387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.70016ms","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-20T14:23:15.163919Z","caller":"traceutil/trace.go:171","msg":"trace[955933028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2775; }","duration":"335.775552ms","start":"2026-04-20T14:23:14.828132Z","end":"2026-04-20T14:23:15.163908Z","steps":["trace[955933028] 'agreement among raft nodes before linearized reading' (duration: 335.58705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.16394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.828126Z","time spent":"335.810082ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:23:15.163412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.499355ms","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-20T14:23:15.164003Z","caller":"traceutil/trace.go:171","msg":"trace[333778185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2775; }","duration":"339.130044ms","start":"2026-04-20T14:23:14.824855Z","end":"2026-04-20T14:23:15.163985Z","steps":["trace[333778185] 'agreement among raft nodes before linearized reading' (duration: 338.444045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.163658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.60636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T14:23:15.164131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.824833Z","time spent":"339.237805ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:23:15.164128Z","caller":"traceutil/trace.go:171","msg":"trace[1267424287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2775; }","duration":"336.097445ms","start":"2026-04-20T14:23:14.828018Z","end":"2026-04-20T14:23:15.164116Z","steps":["trace[1267424287] 'agreement among raft nodes before linearized reading' (duration: 335.580759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.164174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.828003Z","time spent":"336.158746ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:23:15.164183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.786997Z","time spent":"377.142055ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:23:15.164375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.200967ms","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-20T14:23:15.164633Z","caller":"traceutil/trace.go:171","msg":"trace[1713632589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2775; }","duration":"336.51555ms","start":"2026-04-20T14:23:14.828094Z","end":"2026-04-20T14:23:15.164609Z","steps":["trace[1713632589] 'agreement among raft nodes before linearized reading' (duration: 335.749411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:23:15.164865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:23:14.828089Z","time spent":"336.762713ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:24:11.941685Z","caller":"traceutil/trace.go:171","msg":"trace[1245151103] transaction","detail":"{read_only:false; response_revision:3252; number_of_response:1; }","duration":"111.817418ms","start":"2026-04-20T14:24:11.829243Z","end":"2026-04-20T14:24:11.941061Z","steps":["trace[1245151103] 'process raft request' (duration: 71.046825ms)","trace[1245151103] 'compare' (duration: 40.565256ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:46.412822Z","caller":"traceutil/trace.go:171","msg":"trace[1456880062] linearizableReadLoop","detail":"{readStateIndex:3667; appliedIndex:3666; }","duration":"132.136452ms","start":"2026-04-20T14:24:46.280663Z","end":"2026-04-20T14:24:46.412799Z","steps":["trace[1456880062] 'read index received' (duration: 131.872705ms)","trace[1456880062] 'applied index is now lower than readState.Index' (duration: 260.287µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:46.412942Z","caller":"traceutil/trace.go:171","msg":"trace[1498193678] transaction","detail":"{read_only:false; response_revision:3516; number_of_response:1; }","duration":"187.624041ms","start":"2026-04-20T14:24:46.225279Z","end":"2026-04-20T14:24:46.412903Z","steps":["trace[1498193678] 'process raft request' (duration: 187.357814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:46.667096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.651591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T14:24:46.667179Z","caller":"traceutil/trace.go:171","msg":"trace[1546694145] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3516; }","duration":"241.786384ms","start":"2026-04-20T14:24:46.425372Z","end":"2026-04-20T14:24:46.667159Z","steps":["trace[1546694145] 'range keys from in-memory index tree' (duration: 241.466906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:46.667184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.505068ms","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-20T14:24:46.667282Z","caller":"traceutil/trace.go:171","msg":"trace[1704403676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3516; }","duration":"386.644512ms","start":"2026-04-20T14:24:46.280609Z","end":"2026-04-20T14:24:46.667253Z","steps":["trace[1704403676] 'agreement among raft nodes before linearized reading' (duration: 132.322107ms)","trace[1704403676] 'range keys from in-memory index tree' (duration: 254.077269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:46.667325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:46.280593Z","time spent":"386.723325ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:24:46.667327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.95755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-20T14:24:46.667366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.361245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-20T14:24:46.667444Z","caller":"traceutil/trace.go:171","msg":"trace[1535307816] linearizableReadLoop","detail":"{readStateIndex:3668; appliedIndex:3667; }","duration":"222.695816ms","start":"2026-04-20T14:24:46.44473Z","end":"2026-04-20T14:24:46.667426Z","steps":["trace[1535307816] 'read index received' (duration: 69.664734ms)","trace[1535307816] 'applied index is now lower than readState.Index' (duration: 153.029952ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:46.667455Z","caller":"traceutil/trace.go:171","msg":"trace[1215157724] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3516; }","duration":"297.832328ms","start":"2026-04-20T14:24:46.369594Z","end":"2026-04-20T14:24:46.667427Z","steps":["trace[1215157724] 'agreement among raft nodes before linearized reading' (duration: 43.357308ms)","trace[1215157724] 'range keys from in-memory index tree' (duration: 253.901173ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:46.6675Z","caller":"traceutil/trace.go:171","msg":"trace[569844788] transaction","detail":"{read_only:false; response_revision:3517; number_of_response:1; }","duration":"229.394267ms","start":"2026-04-20T14:24:46.438087Z","end":"2026-04-20T14:24:46.667481Z","steps":["trace[569844788] 'process raft request' (duration: 76.201031ms)","trace[569844788] 'compare' (duration: 152.746684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:46.667545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.815969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:46.667584Z","caller":"traceutil/trace.go:171","msg":"trace[549530986] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3517; }","duration":"222.88486ms","start":"2026-04-20T14:24:46.444687Z","end":"2026-04-20T14:24:46.667572Z","steps":["trace[549530986] 'agreement among raft nodes before linearized reading' (duration: 222.793278ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:46.669946Z","caller":"traceutil/trace.go:171","msg":"trace[1022965172] transaction","detail":"{read_only:false; response_revision:3518; number_of_response:1; }","duration":"190.342035ms","start":"2026-04-20T14:24:46.479578Z","end":"2026-04-20T14:24:46.66992Z","steps":["trace[1022965172] 'process raft request' (duration: 190.12461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:46.920723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.109872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:24:46.920948Z","caller":"traceutil/trace.go:171","msg":"trace[124929284] linearizableReadLoop","detail":"{readStateIndex:3672; appliedIndex:3670; }","duration":"203.41312ms","start":"2026-04-20T14:24:46.717512Z","end":"2026-04-20T14:24:46.920925Z","steps":["trace[124929284] 'read index received' (duration: 64.037861ms)","trace[124929284] 'applied index is now lower than readState.Index' (duration: 139.373929ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:46.92101Z","caller":"traceutil/trace.go:171","msg":"trace[1321621998] transaction","detail":"{read_only:false; response_revision:3520; number_of_response:1; }","duration":"246.755369ms","start":"2026-04-20T14:24:46.674232Z","end":"2026-04-20T14:24:46.920988Z","steps":["trace[1321621998] 'process raft request' (duration: 107.273777ms)","trace[1321621998] 'compare' (duration: 138.902566ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:46.921031Z","caller":"traceutil/trace.go:171","msg":"trace[1769600201] transaction","detail":"{read_only:false; response_revision:3521; number_of_response:1; }","duration":"246.566823ms","start":"2026-04-20T14:24:46.674435Z","end":"2026-04-20T14:24:46.921002Z","steps":["trace[1769600201] 'process raft request' (duration: 246.401449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:46.921164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.192475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:46.921241Z","caller":"traceutil/trace.go:171","msg":"trace[1890963815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3521; }","duration":"242.331118ms","start":"2026-04-20T14:24:46.678892Z","end":"2026-04-20T14:24:46.921223Z","steps":["trace[1890963815] 'agreement among raft nodes before linearized reading' (duration: 242.129503ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:46.923534Z","caller":"traceutil/trace.go:171","msg":"trace[842894926] transaction","detail":"{read_only:false; response_revision:3522; number_of_response:1; }","duration":"145.717782ms","start":"2026-04-20T14:24:46.777799Z","end":"2026-04-20T14:24:46.923517Z","steps":["trace[842894926] 'process raft request' (duration: 145.307871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:46.923608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.058458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:46.923694Z","caller":"traceutil/trace.go:171","msg":"trace[1052223394] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/alertmanagers.monitoring.coreos.com; range_end:; response_count:0; response_revision:3522; }","duration":"186.175421ms","start":"2026-04-20T14:24:46.737501Z","end":"2026-04-20T14:24:46.923676Z","steps":["trace[1052223394] 'agreement among raft nodes before linearized reading' (duration: 186.002546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:46.923693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.80642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T14:24:46.923681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.060355ms","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-20T14:24:46.923756Z","caller":"traceutil/trace.go:171","msg":"trace[33152350] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:3522; }","duration":"136.909183ms","start":"2026-04-20T14:24:46.78683Z","end":"2026-04-20T14:24:46.923739Z","steps":["trace[33152350] 'agreement among raft nodes before linearized reading' (duration: 136.717118ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:46.923787Z","caller":"traceutil/trace.go:171","msg":"trace[691740791] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3522; }","duration":"116.226599ms","start":"2026-04-20T14:24:46.807525Z","end":"2026-04-20T14:24:46.923751Z","steps":["trace[691740791] 'agreement among raft nodes before linearized reading' (duration: 115.996523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:47.220009Z","caller":"traceutil/trace.go:171","msg":"trace[1066995213] linearizableReadLoop","detail":"{readStateIndex:3678; appliedIndex:3677; }","duration":"223.452684ms","start":"2026-04-20T14:24:46.996531Z","end":"2026-04-20T14:24:47.219984Z","steps":["trace[1066995213] 'read index received' (duration: 177.986523ms)","trace[1066995213] 'applied index is now lower than readState.Index' (duration: 45.465051ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:47.220156Z","caller":"traceutil/trace.go:171","msg":"trace[547118645] transaction","detail":"{read_only:false; response_revision:3526; number_of_response:1; }","duration":"232.947602ms","start":"2026-04-20T14:24:46.987182Z","end":"2026-04-20T14:24:47.22013Z","steps":["trace[547118645] 'process raft request' (duration: 187.670396ms)","trace[547118645] 'compare' (duration: 44.444034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:47.2204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.857435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:24:47.220483Z","caller":"traceutil/trace.go:171","msg":"trace[555841733] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:3526; }","duration":"223.985238ms","start":"2026-04-20T14:24:46.996486Z","end":"2026-04-20T14:24:47.220472Z","steps":["trace[555841733] 'agreement among raft nodes before linearized reading' (duration: 223.584898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:47.629096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.569064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:24:47.629205Z","caller":"traceutil/trace.go:171","msg":"trace[1056091254] transaction","detail":"{read_only:false; response_revision:3528; number_of_response:1; }","duration":"354.228086ms","start":"2026-04-20T14:24:47.274957Z","end":"2026-04-20T14:24:47.629185Z","steps":["trace[1056091254] 'process raft request' (duration: 91.474208ms)","trace[1056091254] 'compare' (duration: 262.401248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:47.629311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:47.27492Z","time spent":"354.345029ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24378,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:24:47.659432Z","caller":"traceutil/trace.go:171","msg":"trace[1352176832] linearizableReadLoop","detail":"{readStateIndex:3681; appliedIndex:3680; }","duration":"262.79994ms","start":"2026-04-20T14:24:47.396607Z","end":"2026-04-20T14:24:47.659407Z","steps":["trace[1352176832] 'read index received' (duration: 262.030709ms)","trace[1352176832] 'applied index is now lower than readState.Index' (duration: 765.771µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:47.659474Z","caller":"traceutil/trace.go:171","msg":"trace[692119478] transaction","detail":"{read_only:false; response_revision:3529; number_of_response:1; }","duration":"357.875666ms","start":"2026-04-20T14:24:47.301572Z","end":"2026-04-20T14:24:47.659447Z","steps":["trace[692119478] 'process raft request' (duration: 357.240879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:47.659611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.970954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:24:47.659616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:47.301525Z","time spent":"358.010209ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":276768,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:24:47.659672Z","caller":"traceutil/trace.go:171","msg":"trace[839911805] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3529; }","duration":"263.092677ms","start":"2026-04-20T14:24:47.396562Z","end":"2026-04-20T14:24:47.659655Z","steps":["trace[839911805] 'agreement among raft nodes before linearized reading' (duration: 262.952394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:47.948073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.425073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:24:47.94824Z","caller":"traceutil/trace.go:171","msg":"trace[1368689411] transaction","detail":"{read_only:false; response_revision:3532; number_of_response:1; }","duration":"251.101183ms","start":"2026-04-20T14:24:47.697123Z","end":"2026-04-20T14:24:47.948224Z","steps":["trace[1368689411] 'process raft request' (duration: 251.049371ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:47.948279Z","caller":"traceutil/trace.go:171","msg":"trace[1370247296] transaction","detail":"{read_only:false; response_revision:3531; number_of_response:1; }","duration":"260.823955ms","start":"2026-04-20T14:24:47.687427Z","end":"2026-04-20T14:24:47.948251Z","steps":["trace[1370247296] 'process raft request' (duration: 61.151986ms)","trace[1370247296] 'compare' (duration: 199.168366ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:48.098878Z","caller":"traceutil/trace.go:171","msg":"trace[2074810157] linearizableReadLoop","detail":"{readStateIndex:3685; appliedIndex:3682; }","duration":"332.107499ms","start":"2026-04-20T14:24:47.76675Z","end":"2026-04-20T14:24:48.098858Z","steps":["trace[2074810157] 'read index received' (duration: 181.20658ms)","trace[2074810157] 'applied index is now lower than readState.Index' (duration: 150.899939ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:48.098934Z","caller":"traceutil/trace.go:171","msg":"trace[1518760576] transaction","detail":"{read_only:false; response_revision:3534; number_of_response:1; }","duration":"178.673522ms","start":"2026-04-20T14:24:47.920234Z","end":"2026-04-20T14:24:48.098908Z","steps":["trace[1518760576] 'process raft request' (duration: 178.565469ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.098971Z","caller":"traceutil/trace.go:171","msg":"trace[777048528] transaction","detail":"{read_only:false; response_revision:3533; number_of_response:1; }","duration":"349.576641ms","start":"2026-04-20T14:24:47.749369Z","end":"2026-04-20T14:24:48.098946Z","steps":["trace[777048528] 'process raft request' (duration: 317.7306ms)","trace[777048528] 'compare' (duration: 31.41853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:48.09907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.317634ms","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-20T14:24:48.099111Z","caller":"traceutil/trace.go:171","msg":"trace[1798465335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3534; }","duration":"332.367455ms","start":"2026-04-20T14:24:47.766734Z","end":"2026-04-20T14:24:48.099101Z","steps":["trace[1798465335] 'agreement among raft nodes before linearized reading' (duration: 332.233702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.099093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:47.749314Z","time spent":"349.705885ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":276777,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:24:48.099141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:47.766647Z","time spent":"332.487618ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:24:48.149525Z","caller":"traceutil/trace.go:171","msg":"trace[2047092965] transaction","detail":"{read_only:false; response_revision:3535; number_of_response:1; }","duration":"186.293264ms","start":"2026-04-20T14:24:47.963213Z","end":"2026-04-20T14:24:48.149506Z","steps":["trace[2047092965] 'process raft request' (duration: 186.198091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.150028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.851079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-20T14:24:48.150047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.586581ms","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-20T14:24:48.150118Z","caller":"traceutil/trace.go:171","msg":"trace[445708607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3535; }","duration":"231.684233ms","start":"2026-04-20T14:24:47.918422Z","end":"2026-04-20T14:24:48.150107Z","steps":["trace[445708607] 'agreement among raft nodes before linearized reading' (duration: 231.500718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.15014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.704053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-20T14:24:48.150158Z","caller":"traceutil/trace.go:171","msg":"trace[2075016120] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3535; }","duration":"231.743594ms","start":"2026-04-20T14:24:47.91841Z","end":"2026-04-20T14:24:48.150154Z","steps":["trace[2075016120] 'agreement among raft nodes before linearized reading' (duration: 231.695723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.150283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.884874ms","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-20T14:24:48.1503Z","caller":"traceutil/trace.go:171","msg":"trace[579682466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3535; }","duration":"232.933036ms","start":"2026-04-20T14:24:47.917363Z","end":"2026-04-20T14:24:48.150296Z","steps":["trace[579682466] 'agreement among raft nodes before linearized reading' (duration: 232.880564ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.150088Z","caller":"traceutil/trace.go:171","msg":"trace[1608882785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3535; }","duration":"265.951251ms","start":"2026-04-20T14:24:47.884124Z","end":"2026-04-20T14:24:48.150075Z","steps":["trace[1608882785] 'agreement among raft nodes before linearized reading' (duration: 265.756176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.150334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.534475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:48.150356Z","caller":"traceutil/trace.go:171","msg":"trace[157957253] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3535; }","duration":"191.569575ms","start":"2026-04-20T14:24:47.958779Z","end":"2026-04-20T14:24:48.150348Z","steps":["trace[157957253] 'agreement among raft nodes before linearized reading' (duration: 191.520024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.451729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:24:48.452033Z","caller":"traceutil/trace.go:171","msg":"trace[187143347] linearizableReadLoop","detail":"{readStateIndex:3689; appliedIndex:3687; }","duration":"297.031428ms","start":"2026-04-20T14:24:48.154988Z","end":"2026-04-20T14:24:48.45202Z","steps":["trace[187143347] 'read index received' (duration: 71.886659ms)","trace[187143347] 'applied index is now lower than readState.Index' (duration: 225.143929ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:48.452109Z","caller":"traceutil/trace.go:171","msg":"trace[1300139524] transaction","detail":"{read_only:false; response_revision:3538; number_of_response:1; }","duration":"297.10159ms","start":"2026-04-20T14:24:48.155Z","end":"2026-04-20T14:24:48.452102Z","steps":["trace[1300139524] 'process raft request' (duration: 296.898534ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.452161Z","caller":"traceutil/trace.go:171","msg":"trace[1692644067] transaction","detail":"{read_only:false; response_revision:3537; number_of_response:1; }","duration":"301.891609ms","start":"2026-04-20T14:24:48.150184Z","end":"2026-04-20T14:24:48.452076Z","steps":["trace[1692644067] 'process raft request' (duration: 301.639592ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.452354Z","caller":"traceutil/trace.go:171","msg":"trace[3177744] transaction","detail":"{read_only:false; response_revision:3536; number_of_response:1; }","duration":"349.154205ms","start":"2026-04-20T14:24:48.103175Z","end":"2026-04-20T14:24:48.452329Z","steps":["trace[3177744] 'process raft request' (duration: 123.663657ms)","trace[3177744] 'compare' (duration: 224.689037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:48.452392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.396977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:24:48.452387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:48.150171Z","time spent":"302.086535ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25377,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:24:48.452414Z","caller":"traceutil/trace.go:171","msg":"trace[557394929] transaction","detail":"{read_only:false; response_revision:3539; number_of_response:1; }","duration":"296.073982ms","start":"2026-04-20T14:24:48.156331Z","end":"2026-04-20T14:24:48.452405Z","steps":["trace[557394929] 'process raft request' (duration: 295.603009ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.452446Z","caller":"traceutil/trace.go:171","msg":"trace[622328867] transaction","detail":"{read_only:false; response_revision:3541; number_of_response:1; }","duration":"295.437625ms","start":"2026-04-20T14:24:48.157002Z","end":"2026-04-20T14:24:48.45244Z","steps":["trace[622328867] 'process raft request' (duration: 294.992223ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.452422Z","caller":"traceutil/trace.go:171","msg":"trace[1593848629] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3541; }","duration":"297.445479ms","start":"2026-04-20T14:24:48.154968Z","end":"2026-04-20T14:24:48.452413Z","steps":["trace[1593848629] 'agreement among raft nodes before linearized reading' (duration: 297.345566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.452637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:24:48.103155Z","time spent":"349.255508ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:24:48.452363Z","caller":"traceutil/trace.go:171","msg":"trace[567479259] transaction","detail":"{read_only:false; response_revision:3540; number_of_response:1; }","duration":"295.902828ms","start":"2026-04-20T14:24:48.156435Z","end":"2026-04-20T14:24:48.452338Z","steps":["trace[567479259] 'process raft request' (duration: 295.533518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.452832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.333524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:48.45295Z","caller":"traceutil/trace.go:171","msg":"trace[19702478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3541; }","duration":"128.54636ms","start":"2026-04-20T14:24:48.324388Z","end":"2026-04-20T14:24:48.452935Z","steps":["trace[19702478] 'agreement among raft nodes before linearized reading' (duration: 128.230912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.864015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.057176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:24:48.864373Z","caller":"traceutil/trace.go:171","msg":"trace[947230957] transaction","detail":"{read_only:false; response_revision:3545; number_of_response:1; }","duration":"212.115455ms","start":"2026-04-20T14:24:48.65222Z","end":"2026-04-20T14:24:48.864336Z","steps":["trace[947230957] 'process raft request' (duration: 43.654969ms)","trace[947230957] 'compare' (duration: 167.8577ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:24:48.864664Z","caller":"traceutil/trace.go:171","msg":"trace[1850326033] linearizableReadLoop","detail":"{readStateIndex:3697; appliedIndex:3696; }","duration":"186.586915ms","start":"2026-04-20T14:24:48.678047Z","end":"2026-04-20T14:24:48.864634Z","steps":["trace[1850326033] 'read index received' (duration: 17.78593ms)","trace[1850326033] 'applied index is now lower than readState.Index' (duration: 168.797495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:24:48.864843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.79728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:48.864918Z","caller":"traceutil/trace.go:171","msg":"trace[1213529378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3545; }","duration":"186.917264ms","start":"2026-04-20T14:24:48.677985Z","end":"2026-04-20T14:24:48.864902Z","steps":["trace[1213529378] 'agreement among raft nodes before linearized reading' (duration: 186.779211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.865638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.334887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:24:48.865729Z","caller":"traceutil/trace.go:171","msg":"trace[1572471184] transaction","detail":"{read_only:false; response_revision:3546; number_of_response:1; }","duration":"133.597346ms","start":"2026-04-20T14:24:48.732103Z","end":"2026-04-20T14:24:48.8657Z","steps":["trace[1572471184] 'process raft request' (duration: 132.378452ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:24:48.865765Z","caller":"traceutil/trace.go:171","msg":"trace[111853693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com; range_end:; response_count:0; response_revision:3546; }","duration":"173.458461ms","start":"2026-04-20T14:24:48.692258Z","end":"2026-04-20T14:24:48.865717Z","steps":["trace[111853693] 'agreement among raft nodes before linearized reading' (duration: 173.320026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:24:48.866128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.433936ms","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-20T14:24:48.866185Z","caller":"traceutil/trace.go:171","msg":"trace[348813907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3546; }","duration":"138.536209ms","start":"2026-04-20T14:24:48.727636Z","end":"2026-04-20T14:24:48.866172Z","steps":["trace[348813907] 'agreement among raft nodes before linearized reading' (duration: 138.323344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:02.644967Z","caller":"traceutil/trace.go:171","msg":"trace[523958156] transaction","detail":"{read_only:false; response_revision:3658; number_of_response:1; }","duration":"106.185069ms","start":"2026-04-20T14:25:02.538757Z","end":"2026-04-20T14:25:02.644942Z","steps":["trace[523958156] 'process raft request' (duration: 106.098706ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:02.64537Z","caller":"traceutil/trace.go:171","msg":"trace[640561533] transaction","detail":"{read_only:false; response_revision:3657; number_of_response:1; }","duration":"106.953078ms","start":"2026-04-20T14:25:02.538397Z","end":"2026-04-20T14:25:02.64535Z","steps":["trace[640561533] 'process raft request' (duration: 71.109798ms)","trace[640561533] 'compare' (duration: 35.214144ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:02.645552Z","caller":"traceutil/trace.go:171","msg":"trace[381711291] transaction","detail":"{read_only:false; response_revision:3659; number_of_response:1; }","duration":"101.937539ms","start":"2026-04-20T14:25:02.543599Z","end":"2026-04-20T14:25:02.645536Z","steps":["trace[381711291] 'process raft request' (duration: 101.306653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:39.42719Z","caller":"traceutil/trace.go:171","msg":"trace[26042381] linearizableReadLoop","detail":"{readStateIndex:4531; appliedIndex:4530; }","duration":"149.52524ms","start":"2026-04-20T14:25:39.27763Z","end":"2026-04-20T14:25:39.427156Z","steps":["trace[26042381] 'read index received' (duration: 118.01748ms)","trace[26042381] 'applied index is now lower than readState.Index' (duration: 31.50601ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:39.427224Z","caller":"traceutil/trace.go:171","msg":"trace[289795159] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"149.654173ms","start":"2026-04-20T14:25:39.277544Z","end":"2026-04-20T14:25:39.427198Z","steps":["trace[289795159] 'process raft request' (duration: 118.108392ms)","trace[289795159] 'compare' (duration: 31.234454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:39.427437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.792326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-m27hk\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-04-20T14:25:39.427482Z","caller":"traceutil/trace.go:171","msg":"trace[260824337] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-m27hk; range_end:; response_count:1; response_revision:4342; }","duration":"149.879069ms","start":"2026-04-20T14:25:39.277592Z","end":"2026-04-20T14:25:39.427471Z","steps":["trace[260824337] 'agreement among raft nodes before linearized reading' (duration: 149.694734ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:40.161764Z","caller":"traceutil/trace.go:171","msg":"trace[1715368679] transaction","detail":"{read_only:false; response_revision:4381; number_of_response:1; }","duration":"116.025117ms","start":"2026-04-20T14:25:40.045719Z","end":"2026-04-20T14:25:40.161744Z","steps":["trace[1715368679] 'process raft request' (duration: 115.844722ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:45.23878Z","caller":"traceutil/trace.go:171","msg":"trace[789072473] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"379.924126ms","start":"2026-04-20T14:25:44.858831Z","end":"2026-04-20T14:25:45.238755Z","steps":["trace[789072473] 'process raft request' (duration: 379.693521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:45.238922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:44.858806Z","time spent":"380.042219ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:45.243836Z","caller":"traceutil/trace.go:171","msg":"trace[110136313] linearizableReadLoop","detail":"{readStateIndex:4684; appliedIndex:4682; }","duration":"310.496318ms","start":"2026-04-20T14:25:44.933311Z","end":"2026-04-20T14:25:45.243807Z","steps":["trace[110136313] 'read index received' (duration: 305.186459ms)","trace[110136313] 'applied index is now lower than readState.Index' (duration: 5.308409ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:45.243906Z","caller":"traceutil/trace.go:171","msg":"trace[1759295145] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"349.10268ms","start":"2026-04-20T14:25:44.894773Z","end":"2026-04-20T14:25:45.243875Z","steps":["trace[1759295145] 'process raft request' (duration: 348.886175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:45.244061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:44.894738Z","time spent":"349.242593ms","remote":"127.0.0.1:59074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2661,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:45.244183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.860837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T14:25:45.244234Z","caller":"traceutil/trace.go:171","msg":"trace[1990431458] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4490; }","duration":"310.99367ms","start":"2026-04-20T14:25:44.933227Z","end":"2026-04-20T14:25:45.244221Z","steps":["trace[1990431458] 'agreement among raft nodes before linearized reading' (duration: 310.734194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:45.244281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:44.933204Z","time spent":"311.065762ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T14:25:45.244326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.066586ms","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-20T14:25:45.244363Z","caller":"traceutil/trace.go:171","msg":"trace[1742858431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4490; }","duration":"258.115566ms","start":"2026-04-20T14:25:44.986237Z","end":"2026-04-20T14:25:45.244352Z","steps":["trace[1742858431] 'agreement among raft nodes before linearized reading' (duration: 257.965143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:45.244918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.033731ms","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-20T14:25:45.245024Z","caller":"traceutil/trace.go:171","msg":"trace[1338925338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4490; }","duration":"272.166994ms","start":"2026-04-20T14:25:44.972832Z","end":"2026-04-20T14:25:45.244999Z","steps":["trace[1338925338] 'agreement among raft nodes before linearized reading' (duration: 271.324715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:45.245035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.995027ms","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-20T14:25:45.245072Z","caller":"traceutil/trace.go:171","msg":"trace[300388994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4490; }","duration":"263.047288ms","start":"2026-04-20T14:25:44.982014Z","end":"2026-04-20T14:25:45.245062Z","steps":["trace[300388994] 'agreement among raft nodes before linearized reading' (duration: 262.68063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:46.922254Z","caller":"traceutil/trace.go:171","msg":"trace[2033495879] linearizableReadLoop","detail":"{readStateIndex:4756; appliedIndex:4755; }","duration":"420.645515ms","start":"2026-04-20T14:25:46.501585Z","end":"2026-04-20T14:25:46.922231Z","steps":["trace[2033495879] 'read index received' (duration: 420.446781ms)","trace[2033495879] 'applied index is now lower than readState.Index' (duration: 197.874µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:46.922353Z","caller":"traceutil/trace.go:171","msg":"trace[705812009] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"561.917626ms","start":"2026-04-20T14:25:46.360424Z","end":"2026-04-20T14:25:46.922342Z","steps":["trace[705812009] 'process raft request' (duration: 561.66902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:46.922436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:46.360407Z","time spent":"561.972937ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:46.922431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.82906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-143095be-73d1-425d-a009-20c56f9ec4be\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-04-20T14:25:46.922531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.879131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:46.923174Z","caller":"traceutil/trace.go:171","msg":"trace[254261639] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4562; }","duration":"244.606437ms","start":"2026-04-20T14:25:46.67855Z","end":"2026-04-20T14:25:46.923156Z","steps":["trace[254261639] 'agreement among raft nodes before linearized reading' (duration: 243.961883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:46.922584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.532143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:25:46.923347Z","caller":"traceutil/trace.go:171","msg":"trace[1882709365] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:4562; }","duration":"133.358581ms","start":"2026-04-20T14:25:46.789979Z","end":"2026-04-20T14:25:46.923338Z","steps":["trace[1882709365] 'agreement among raft nodes before linearized reading' (duration: 132.455981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:46.922607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.653471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-04-20T14:25:46.923665Z","caller":"traceutil/trace.go:171","msg":"trace[621305981] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4562; }","duration":"283.774696ms","start":"2026-04-20T14:25:46.639878Z","end":"2026-04-20T14:25:46.923653Z","steps":["trace[621305981] 'agreement among raft nodes before linearized reading' (duration: 282.523428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:46.92261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.287886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:46.923781Z","caller":"traceutil/trace.go:171","msg":"trace[1080834189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4562; }","duration":"243.528894ms","start":"2026-04-20T14:25:46.680243Z","end":"2026-04-20T14:25:46.923772Z","steps":["trace[1080834189] 'agreement among raft nodes before linearized reading' (duration: 242.263625ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:46.924122Z","caller":"traceutil/trace.go:171","msg":"trace[864680910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-143095be-73d1-425d-a009-20c56f9ec4be; range_end:; response_count:1; response_revision:4562; }","duration":"421.516345ms","start":"2026-04-20T14:25:46.501531Z","end":"2026-04-20T14:25:46.923047Z","steps":["trace[864680910] 'agreement among raft nodes before linearized reading' (duration: 420.81902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:46.924162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:46.501505Z","time spent":"422.64403ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3790,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-143095be-73d1-425d-a009-20c56f9ec4be\" "} {"level":"info","ts":"2026-04-20T14:25:47.147346Z","caller":"traceutil/trace.go:171","msg":"trace[1646671557] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"117.291874ms","start":"2026-04-20T14:25:47.030028Z","end":"2026-04-20T14:25:47.14732Z","steps":["trace[1646671557] 'process raft request' (duration: 80.544452ms)","trace[1646671557] 'compare' (duration: 36.62018ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:47.277372Z","caller":"traceutil/trace.go:171","msg":"trace[760844780] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4565; }","duration":"122.193024ms","start":"2026-04-20T14:25:47.155157Z","end":"2026-04-20T14:25:47.27735Z","steps":["trace[760844780] 'process raft request' (duration: 108.517708ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:49.455219Z","caller":"traceutil/trace.go:171","msg":"trace[391567324] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"162.659399ms","start":"2026-04-20T14:25:49.292541Z","end":"2026-04-20T14:25:49.4552Z","steps":["trace[391567324] 'process raft request' (duration: 162.518556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:49.8097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.419519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:25:49.809782Z","caller":"traceutil/trace.go:171","msg":"trace[1757786130] linearizableReadLoop","detail":"{readStateIndex:4778; appliedIndex:4776; }","duration":"513.72526ms","start":"2026-04-20T14:25:49.296044Z","end":"2026-04-20T14:25:49.809769Z","steps":["trace[1757786130] 'read index received' (duration: 159.013108ms)","trace[1757786130] 'applied index is now lower than readState.Index' (duration: 354.711292ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:49.809817Z","caller":"traceutil/trace.go:171","msg":"trace[1857676478] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"516.113874ms","start":"2026-04-20T14:25:49.29368Z","end":"2026-04-20T14:25:49.809794Z","steps":["trace[1857676478] 'process raft request' (duration: 364.51869ms)","trace[1857676478] 'compare' (duration: 151.315597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:49.809924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.878634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-04-20T14:25:49.809921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.293652Z","time spent":"516.211755ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:49.809945Z","caller":"traceutil/trace.go:171","msg":"trace[201225832] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4582; }","duration":"513.914764ms","start":"2026-04-20T14:25:49.296023Z","end":"2026-04-20T14:25:49.809938Z","steps":["trace[201225832] 'agreement among raft nodes before linearized reading' (duration: 513.813702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:49.80997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.296015Z","time spent":"513.949125ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T14:25:49.810132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.474602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:25:49.810184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.054507ms","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-20T14:25:49.810183Z","caller":"traceutil/trace.go:171","msg":"trace[1541331893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4582; }","duration":"129.567783ms","start":"2026-04-20T14:25:49.680603Z","end":"2026-04-20T14:25:49.81017Z","steps":["trace[1541331893] 'agreement among raft nodes before linearized reading' (duration: 129.489111ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:49.810208Z","caller":"traceutil/trace.go:171","msg":"trace[150023408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4582; }","duration":"514.108159ms","start":"2026-04-20T14:25:49.296093Z","end":"2026-04-20T14:25:49.810201Z","steps":["trace[150023408] 'agreement among raft nodes before linearized reading' (duration: 514.019197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:49.810228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.296078Z","time spent":"514.146009ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:25:49.810245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.099298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:25:49.810313Z","caller":"traceutil/trace.go:171","msg":"trace[1164959545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4582; }","duration":"514.17938ms","start":"2026-04-20T14:25:49.296117Z","end":"2026-04-20T14:25:49.810296Z","steps":["trace[1164959545] 'agreement among raft nodes before linearized reading' (duration: 513.970115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:49.810354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.296056Z","time spent":"514.288393ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:25:50.200749Z","caller":"traceutil/trace.go:171","msg":"trace[1627651393] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"385.615398ms","start":"2026-04-20T14:25:49.815109Z","end":"2026-04-20T14:25:50.200724Z","steps":["trace[1627651393] 'process raft request' (duration: 385.474675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.200882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.815084Z","time spent":"385.73152ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:50.614522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.650589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:25:50.615169Z","caller":"traceutil/trace.go:171","msg":"trace[139536859] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"799.834919ms","start":"2026-04-20T14:25:49.815306Z","end":"2026-04-20T14:25:50.615141Z","steps":["trace[139536859] 'process raft request' (duration: 572.476364ms)","trace[139536859] 'compare' (duration: 226.521556ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:50.615368Z","caller":"traceutil/trace.go:171","msg":"trace[1134872328] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4778; }","duration":"750.813398ms","start":"2026-04-20T14:25:49.864535Z","end":"2026-04-20T14:25:50.615349Z","steps":["trace[1134872328] 'read index received' (duration: 336.076886ms)","trace[1134872328] 'applied index is now lower than readState.Index' (duration: 414.735522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:50.61535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.815284Z","time spent":"799.954851ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:50.615552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.434585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T14:25:50.61558Z","caller":"traceutil/trace.go:171","msg":"trace[1821671418] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4586; }","duration":"735.507057ms","start":"2026-04-20T14:25:49.880065Z","end":"2026-04-20T14:25:50.615572Z","steps":["trace[1821671418] 'agreement among raft nodes before linearized reading' (duration: 735.388304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.615603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.880045Z","time spent":"735.552237ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:25:50.615795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.509035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-m27hk.18a816d09e66ce10\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-20T14:25:50.615821Z","caller":"traceutil/trace.go:171","msg":"trace[1278653358] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-m27hk.18a816d09e66ce10; range_end:; response_count:1; response_revision:4586; }","duration":"468.578346ms","start":"2026-04-20T14:25:50.147235Z","end":"2026-04-20T14:25:50.615814Z","steps":["trace[1278653358] 'agreement among raft nodes before linearized reading' (duration: 468.510065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.615847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:50.147214Z","time spent":"468.627508ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":930,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-m27hk.18a816d09e66ce10\" "} {"level":"warn","ts":"2026-04-20T14:25:50.615962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.57195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-143095be-73d1-425d-a009-20c56f9ec4be\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-04-20T14:25:50.616008Z","caller":"traceutil/trace.go:171","msg":"trace[448301219] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"800.650446ms","start":"2026-04-20T14:25:49.815323Z","end":"2026-04-20T14:25:50.615973Z","steps":["trace[448301219] 'process raft request' (duration: 799.304506ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:50.61609Z","caller":"traceutil/trace.go:171","msg":"trace[635227562] transaction","detail":"{read_only:false; response_revision:4586; number_of_response:1; }","duration":"704.687741ms","start":"2026-04-20T14:25:49.911392Z","end":"2026-04-20T14:25:50.61608Z","steps":["trace[635227562] 'process raft request' (duration: 703.818212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.616157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.91136Z","time spent":"704.749643ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:50.616178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.815311Z","time spent":"800.788168ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:50.616162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.697848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:25:50.616272Z","caller":"traceutil/trace.go:171","msg":"trace[360285840] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4586; }","duration":"649.811701ms","start":"2026-04-20T14:25:49.966449Z","end":"2026-04-20T14:25:50.616261Z","steps":["trace[360285840] 'agreement among raft nodes before linearized reading' (duration: 649.623217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.616303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.054833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T14:25:50.616356Z","caller":"traceutil/trace.go:171","msg":"trace[1713693211] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4586; }","duration":"751.920522ms","start":"2026-04-20T14:25:49.864423Z","end":"2026-04-20T14:25:50.616344Z","steps":["trace[1713693211] 'agreement among raft nodes before linearized reading' (duration: 751.024522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.616384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.864402Z","time spent":"751.972783ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:25:50.616419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.966392Z","time spent":"649.972064ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:25:50.616048Z","caller":"traceutil/trace.go:171","msg":"trace[1398939495] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-143095be-73d1-425d-a009-20c56f9ec4be; range_end:; response_count:1; response_revision:4586; }","duration":"678.684613ms","start":"2026-04-20T14:25:49.937345Z","end":"2026-04-20T14:25:50.616029Z","steps":["trace[1398939495] 'agreement among raft nodes before linearized reading' (duration: 678.419267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.616554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.937308Z","time spent":"679.240006ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3976,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-143095be-73d1-425d-a009-20c56f9ec4be\" "} {"level":"info","ts":"2026-04-20T14:25:51.966657Z","caller":"traceutil/trace.go:171","msg":"trace[2091202651] linearizableReadLoop","detail":"{readStateIndex:4818; appliedIndex:4817; }","duration":"143.660449ms","start":"2026-04-20T14:25:51.822967Z","end":"2026-04-20T14:25:51.966627Z","steps":["trace[2091202651] 'read index received' (duration: 139.419366ms)","trace[2091202651] 'applied index is now lower than readState.Index' (duration: 4.239813ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:51.966839Z","caller":"traceutil/trace.go:171","msg":"trace[758631958] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"149.880547ms","start":"2026-04-20T14:25:51.816902Z","end":"2026-04-20T14:25:51.966782Z","steps":["trace[758631958] 'process raft request' (duration: 145.484569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:51.967158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.155761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:25:51.967226Z","caller":"traceutil/trace.go:171","msg":"trace[732631737] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4622; }","duration":"144.282293ms","start":"2026-04-20T14:25:51.822927Z","end":"2026-04-20T14:25:51.96721Z","steps":["trace[732631737] 'agreement among raft nodes before linearized reading' (duration: 143.864864ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:58.353472Z","caller":"traceutil/trace.go:171","msg":"trace[2056847517] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"117.200275ms","start":"2026-04-20T14:25:58.236247Z","end":"2026-04-20T14:25:58.353448Z","steps":["trace[2056847517] 'process raft request' (duration: 116.9982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.0652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182245725156674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:26:02.464309Z","caller":"traceutil/trace.go:171","msg":"trace[579122609] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4960; }","duration":"899.716808ms","start":"2026-04-20T14:26:01.564572Z","end":"2026-04-20T14:26:02.464289Z","steps":["trace[579122609] 'read index received' (duration: 899.644606ms)","trace[579122609] 'applied index is now lower than readState.Index' (duration: 71.382µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:02.464423Z","caller":"traceutil/trace.go:171","msg":"trace[1200849867] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"983.80256ms","start":"2026-04-20T14:26:01.480577Z","end":"2026-04-20T14:26:02.46438Z","steps":["trace[1200849867] 'process raft request' (duration: 983.551504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.464516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.923062ms","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-20T14:26:02.464556Z","caller":"traceutil/trace.go:171","msg":"trace[890663277] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4759; }","duration":"899.981903ms","start":"2026-04-20T14:26:01.56456Z","end":"2026-04-20T14:26:02.464542Z","steps":["trace[890663277] 'agreement among raft nodes before linearized reading' (duration: 899.84547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.464589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:01.564484Z","time spent":"900.097415ms","remote":"127.0.0.1:60276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-20T14:26:02.464587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:01.480555Z","time spent":"983.945543ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10794,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:02.909635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.664701ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T14:26:02.909995Z","caller":"traceutil/trace.go:171","msg":"trace[601210327] linearizableReadLoop","detail":"{readStateIndex:4962; appliedIndex:4961; }","duration":"445.585469ms","start":"2026-04-20T14:26:02.464378Z","end":"2026-04-20T14:26:02.909963Z","steps":["trace[601210327] 'read index received' (duration: 119.509979ms)","trace[601210327] 'applied index is now lower than readState.Index' (duration: 326.07273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:02.910138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.841732ms","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-20T14:26:02.910215Z","caller":"traceutil/trace.go:171","msg":"trace[137603182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4759; }","duration":"543.965974ms","start":"2026-04-20T14:26:02.366232Z","end":"2026-04-20T14:26:02.910198Z","steps":["trace[137603182] 'agreement among raft nodes before linearized reading' (duration: 543.7368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.910247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.366213Z","time spent":"544.028306ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:26:02.910286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.98104ms","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-20T14:26:02.910334Z","caller":"traceutil/trace.go:171","msg":"trace[1447231477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4759; }","duration":"847.083941ms","start":"2026-04-20T14:26:02.063235Z","end":"2026-04-20T14:26:02.910319Z","steps":["trace[1447231477] 'agreement among raft nodes before linearized reading' (duration: 846.834116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.91038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.063209Z","time spent":"847.160633ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:26:02.910472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.17706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-20T14:26:02.910507Z","caller":"traceutil/trace.go:171","msg":"trace[1415312530] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4759; }","duration":"458.256873ms","start":"2026-04-20T14:26:02.452239Z","end":"2026-04-20T14:26:02.910496Z","steps":["trace[1415312530] 'agreement among raft nodes before linearized reading' (duration: 458.163481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.91053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.452219Z","time spent":"458.304333ms","remote":"127.0.0.1:60314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-20T14:26:02.910795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336299154s","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-20T14:26:02.910823Z","caller":"traceutil/trace.go:171","msg":"trace[156291468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4759; }","duration":"1.336349304s","start":"2026-04-20T14:26:01.574467Z","end":"2026-04-20T14:26:02.910816Z","steps":["trace[156291468] 'agreement among raft nodes before linearized reading' (duration: 1.336280753s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.910842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:01.574457Z","time spent":"1.336380295s","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:26:02.911182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.485946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"warn","ts":"2026-04-20T14:26:02.911242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229155655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:02.911272Z","caller":"traceutil/trace.go:171","msg":"trace[586148239] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4759; }","duration":"911.65295ms","start":"2026-04-20T14:26:01.999598Z","end":"2026-04-20T14:26:02.911251Z","steps":["trace[586148239] 'agreement among raft nodes before linearized reading' (duration: 910.497745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.911319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:01.999568Z","time spent":"911.742812ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2126,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-04-20T14:26:02.911469Z","caller":"traceutil/trace.go:171","msg":"trace[1324023946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4759; }","duration":"1.23037591s","start":"2026-04-20T14:26:01.680908Z","end":"2026-04-20T14:26:02.911283Z","steps":["trace[1324023946] 'agreement among raft nodes before linearized reading' (duration: 1.229166535s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.911678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:01.680869Z","time spent":"1.230645576s","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:26:02.923647Z","caller":"traceutil/trace.go:171","msg":"trace[977687131] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"451.909116ms","start":"2026-04-20T14:26:02.47172Z","end":"2026-04-20T14:26:02.923629Z","steps":["trace[977687131] 'process raft request' (duration: 451.683711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.923803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.471684Z","time spent":"452.026149ms","remote":"127.0.0.1:60406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:02.923961Z","caller":"traceutil/trace.go:171","msg":"trace[1626487531] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"451.277082ms","start":"2026-04-20T14:26:02.472658Z","end":"2026-04-20T14:26:02.923935Z","steps":["trace[1626487531] 'process raft request' (duration: 450.918904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.924099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.47264Z","time spent":"451.385124ms","remote":"127.0.0.1:60288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:02.925227Z","caller":"traceutil/trace.go:171","msg":"trace[892967284] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"450.839463ms","start":"2026-04-20T14:26:02.474375Z","end":"2026-04-20T14:26:02.925215Z","steps":["trace[892967284] 'process raft request' (duration: 450.789852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.925381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.47434Z","time spent":"450.973186ms","remote":"127.0.0.1:60288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:02.925264Z","caller":"traceutil/trace.go:171","msg":"trace[1270820767] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"452.490457ms","start":"2026-04-20T14:26:02.472753Z","end":"2026-04-20T14:26:02.925244Z","steps":["trace[1270820767] 'process raft request' (duration: 451.274292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:02.925478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.472746Z","time spent":"452.699962ms","remote":"127.0.0.1:60406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:03.017432Z","caller":"traceutil/trace.go:171","msg":"trace[1471693340] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"363.597857ms","start":"2026-04-20T14:26:02.653798Z","end":"2026-04-20T14:26:03.017396Z","steps":["trace[1471693340] 'process raft request' (duration: 306.993951ms)","trace[1471693340] 'compare' (duration: 56.267269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:03.017602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.653769Z","time spent":"363.74532ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:03.017896Z","caller":"traceutil/trace.go:171","msg":"trace[120393020] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4962; }","duration":"107.847257ms","start":"2026-04-20T14:26:02.910027Z","end":"2026-04-20T14:26:03.017874Z","steps":["trace[120393020] 'read index received' (duration: 13.444979ms)","trace[120393020] 'applied index is now lower than readState.Index' (duration: 94.401278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:03.018085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.183799ms","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-20T14:26:03.018141Z","caller":"traceutil/trace.go:171","msg":"trace[1122183092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4767; }","duration":"351.295473ms","start":"2026-04-20T14:26:02.666836Z","end":"2026-04-20T14:26:03.018131Z","steps":["trace[1122183092] 'agreement among raft nodes before linearized reading' (duration: 351.064357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.813557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T14:26:03.018203Z","caller":"traceutil/trace.go:171","msg":"trace[1976678067] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"101.505522ms","start":"2026-04-20T14:26:02.916685Z","end":"2026-04-20T14:26:03.018191Z","steps":["trace[1976678067] 'process raft request' (duration: 101.135763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.054058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T14:26:03.018297Z","caller":"traceutil/trace.go:171","msg":"trace[797822684] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4767; }","duration":"345.096949ms","start":"2026-04-20T14:26:02.673195Z","end":"2026-04-20T14:26:03.018292Z","steps":["trace[797822684] 'agreement among raft nodes before linearized reading' (duration: 345.024587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.207938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"warn","ts":"2026-04-20T14:26:03.018317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.673185Z","time spent":"345.127689ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-20T14:26:03.018385Z","caller":"traceutil/trace.go:171","msg":"trace[1038386789] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4767; }","duration":"416.440533ms","start":"2026-04-20T14:26:02.601934Z","end":"2026-04-20T14:26:03.018374Z","steps":["trace[1038386789] 'agreement among raft nodes before linearized reading' (duration: 416.089854ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.018441Z","caller":"traceutil/trace.go:171","msg":"trace[1600325382] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4767; }","duration":"175.015763ms","start":"2026-04-20T14:26:02.84321Z","end":"2026-04-20T14:26:03.018226Z","steps":["trace[1600325382] 'agreement among raft nodes before linearized reading' (duration: 174.683385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.018347Z","caller":"traceutil/trace.go:171","msg":"trace[955146692] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"101.968932ms","start":"2026-04-20T14:26:02.916368Z","end":"2026-04-20T14:26:03.018337Z","steps":["trace[955146692] 'process raft request' (duration: 101.242726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.794637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4788"} {"level":"warn","ts":"2026-04-20T14:26:03.018569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.191211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:1270"} {"level":"info","ts":"2026-04-20T14:26:03.018882Z","caller":"traceutil/trace.go:171","msg":"trace[183929584] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4767; }","duration":"104.519357ms","start":"2026-04-20T14:26:02.914355Z","end":"2026-04-20T14:26:03.018874Z","steps":["trace[183929584] 'agreement among raft nodes before linearized reading' (duration: 104.104288ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.018877Z","caller":"traceutil/trace.go:171","msg":"trace[260053418] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4767; }","duration":"101.028701ms","start":"2026-04-20T14:26:02.917836Z","end":"2026-04-20T14:26:03.018865Z","steps":["trace[260053418] 'agreement among raft nodes before linearized reading' (duration: 100.760675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.019094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-143095be-73d1-425d-a009-20c56f9ec4be\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-20T14:26:03.018979Z","caller":"traceutil/trace.go:171","msg":"trace[1965077491] range","detail":"{range_begin:/registry/persistentvolumes/pvc-143095be-73d1-425d-a009-20c56f9ec4be; range_end:; response_count:1; response_revision:4767; }","duration":"550.418103ms","start":"2026-04-20T14:26:02.468552Z","end":"2026-04-20T14:26:03.018971Z","steps":["trace[1965077491] 'agreement among raft nodes before linearized reading' (duration: 550.006485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.468537Z","time spent":"550.453644ms","remote":"127.0.0.1:60266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-143095be-73d1-425d-a009-20c56f9ec4be\" "} {"level":"warn","ts":"2026-04-20T14:26:03.018168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.666816Z","time spent":"351.345803ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:26:03.019131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.543348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:03.019249Z","caller":"traceutil/trace.go:171","msg":"trace[973638891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4767; }","duration":"105.692402ms","start":"2026-04-20T14:26:02.913542Z","end":"2026-04-20T14:26:03.019234Z","steps":["trace[973638891] 'agreement among raft nodes before linearized reading' (duration: 104.758092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.0193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.018038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1478"} {"level":"info","ts":"2026-04-20T14:26:03.01934Z","caller":"traceutil/trace.go:171","msg":"trace[544333678] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4767; }","duration":"105.071619ms","start":"2026-04-20T14:26:02.914259Z","end":"2026-04-20T14:26:03.01933Z","steps":["trace[544333678] 'agreement among raft nodes before linearized reading' (duration: 104.978657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.019515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.899486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1462"} {"level":"info","ts":"2026-04-20T14:26:03.019617Z","caller":"traceutil/trace.go:171","msg":"trace[1436196693] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4767; }","duration":"106.044749ms","start":"2026-04-20T14:26:02.913542Z","end":"2026-04-20T14:26:03.019587Z","steps":["trace[1436196693] 'agreement among raft nodes before linearized reading' (duration: 105.735613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.018485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:02.601872Z","time spent":"416.559045ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11521,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T14:26:03.020111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.772881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T14:26:03.020166Z","caller":"traceutil/trace.go:171","msg":"trace[21306639] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4767; }","duration":"170.885113ms","start":"2026-04-20T14:26:02.849263Z","end":"2026-04-20T14:26:03.020148Z","steps":["trace[21306639] 'agreement among raft nodes before linearized reading' (duration: 170.359932ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.017884Z","caller":"traceutil/trace.go:171","msg":"trace[1834894964] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"102.885471ms","start":"2026-04-20T14:26:02.914971Z","end":"2026-04-20T14:26:03.017857Z","steps":["trace[1834894964] 'process raft request' (duration: 102.254597ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.360993Z","caller":"traceutil/trace.go:171","msg":"trace[1260427158] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"298.963931ms","start":"2026-04-20T14:26:03.062005Z","end":"2026-04-20T14:26:03.360969Z","steps":["trace[1260427158] 'process raft request' (duration: 261.973128ms)","trace[1260427158] 'compare' (duration: 36.85084ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:03.361035Z","caller":"traceutil/trace.go:171","msg":"trace[499366366] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"173.174054ms","start":"2026-04-20T14:26:03.187845Z","end":"2026-04-20T14:26:03.361019Z","steps":["trace[499366366] 'process raft request' (duration: 173.116903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.361099Z","caller":"traceutil/trace.go:171","msg":"trace[1454897726] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"249.859508ms","start":"2026-04-20T14:26:03.11121Z","end":"2026-04-20T14:26:03.36107Z","steps":["trace[1454897726] 'read index received' (duration: 212.783453ms)","trace[1454897726] 'applied index is now lower than readState.Index' (duration: 37.072475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:03.361257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.934109ms","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-20T14:26:03.36136Z","caller":"traceutil/trace.go:171","msg":"trace[1099559720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4775; }","duration":"290.03553ms","start":"2026-04-20T14:26:03.071278Z","end":"2026-04-20T14:26:03.361314Z","steps":["trace[1099559720] 'agreement among raft nodes before linearized reading' (duration: 289.795245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.361378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.741217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18a816cea90c4500\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-20T14:26:03.361419Z","caller":"traceutil/trace.go:171","msg":"trace[681538602] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18a816cea90c4500; range_end:; response_count:1; response_revision:4775; }","duration":"299.795748ms","start":"2026-04-20T14:26:03.061614Z","end":"2026-04-20T14:26:03.36141Z","steps":["trace[681538602] 'agreement among raft nodes before linearized reading' (duration: 299.628385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.362263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.565742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10522"} {"level":"info","ts":"2026-04-20T14:26:03.362319Z","caller":"traceutil/trace.go:171","msg":"trace[398667605] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4775; }","duration":"249.656924ms","start":"2026-04-20T14:26:03.112649Z","end":"2026-04-20T14:26:03.362306Z","steps":["trace[398667605] 'agreement among raft nodes before linearized reading' (duration: 249.45685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.362476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.643418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-20T14:26:03.362511Z","caller":"traceutil/trace.go:171","msg":"trace[2108718815] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4775; }","duration":"245.700918ms","start":"2026-04-20T14:26:03.116799Z","end":"2026-04-20T14:26:03.3625Z","steps":["trace[2108718815] 'agreement among raft nodes before linearized reading' (duration: 245.606136ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.546477Z","caller":"traceutil/trace.go:171","msg":"trace[1973743934] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"150.164391ms","start":"2026-04-20T14:26:03.396246Z","end":"2026-04-20T14:26:03.546411Z","steps":["trace[1973743934] 'process raft request' (duration: 149.919456ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:03.546577Z","caller":"traceutil/trace.go:171","msg":"trace[1191512048] linearizableReadLoop","detail":"{readStateIndex:4982; appliedIndex:4979; }","duration":"149.823983ms","start":"2026-04-20T14:26:03.396715Z","end":"2026-04-20T14:26:03.546539Z","steps":["trace[1191512048] 'read index received' (duration: 60.824324ms)","trace[1191512048] 'applied index is now lower than readState.Index' (duration: 88.995228ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:03.546879Z","caller":"traceutil/trace.go:171","msg":"trace[541360401] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"141.76154ms","start":"2026-04-20T14:26:03.404875Z","end":"2026-04-20T14:26:03.546636Z","steps":["trace[541360401] 'process raft request' (duration: 141.465333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:03.54722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.230001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18a816cea84be6f4\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-04-20T14:26:03.547402Z","caller":"traceutil/trace.go:171","msg":"trace[1918070361] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.18a816cea84be6f4; range_end:; response_count:1; response_revision:4780; }","duration":"150.703061ms","start":"2026-04-20T14:26:03.396683Z","end":"2026-04-20T14:26:03.547386Z","steps":["trace[1918070361] 'agreement among raft nodes before linearized reading' (duration: 149.975566ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:13.174456Z","caller":"traceutil/trace.go:171","msg":"trace[1012672715] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"112.573795ms","start":"2026-04-20T14:26:13.061862Z","end":"2026-04-20T14:26:13.174435Z","steps":["trace[1012672715] 'process raft request' (duration: 112.35649ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:13.326952Z","caller":"traceutil/trace.go:171","msg":"trace[1553900886] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"147.824382ms","start":"2026-04-20T14:26:13.179099Z","end":"2026-04-20T14:26:13.326924Z","steps":["trace[1553900886] 'process raft request' (duration: 128.214242ms)","trace[1553900886] 'compare' (duration: 19.406246ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:13.327245Z","caller":"traceutil/trace.go:171","msg":"trace[561273863] linearizableReadLoop","detail":"{readStateIndex:5066; appliedIndex:5063; }","duration":"142.080273ms","start":"2026-04-20T14:26:13.185141Z","end":"2026-04-20T14:26:13.327222Z","steps":["trace[561273863] 'read index received' (duration: 122.187906ms)","trace[561273863] 'applied index is now lower than readState.Index' (duration: 19.891297ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:13.327399Z","caller":"traceutil/trace.go:171","msg":"trace[1683116472] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"148.229122ms","start":"2026-04-20T14:26:13.179157Z","end":"2026-04-20T14:26:13.327386Z","steps":["trace[1683116472] 'process raft request' (duration: 147.72235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:13.327703Z","caller":"traceutil/trace.go:171","msg":"trace[1341898171] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"148.367835ms","start":"2026-04-20T14:26:13.179317Z","end":"2026-04-20T14:26:13.327685Z","steps":["trace[1341898171] 'process raft request' (duration: 147.731151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:13.327721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.976971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:26:13.327762Z","caller":"traceutil/trace.go:171","msg":"trace[1666525831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4859; }","duration":"142.046372ms","start":"2026-04-20T14:26:13.185704Z","end":"2026-04-20T14:26:13.327751Z","steps":["trace[1666525831] 'agreement among raft nodes before linearized reading' (duration: 141.876649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:13.328336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.173066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-20T14:26:13.328409Z","caller":"traceutil/trace.go:171","msg":"trace[829308635] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4859; }","duration":"143.274997ms","start":"2026-04-20T14:26:13.185117Z","end":"2026-04-20T14:26:13.328392Z","steps":["trace[829308635] 'agreement among raft nodes before linearized reading' (duration: 142.299997ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:14.27777Z","caller":"traceutil/trace.go:171","msg":"trace[2104846920] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4867; }","duration":"267.925002ms","start":"2026-04-20T14:26:14.009717Z","end":"2026-04-20T14:26:14.277642Z","steps":["trace[2104846920] 'process raft request' (duration: 225.936726ms)","trace[2104846920] 'compare' (duration: 41.927575ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:29.302795Z","caller":"traceutil/trace.go:171","msg":"trace[881932109] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"110.322015ms","start":"2026-04-20T14:26:29.192451Z","end":"2026-04-20T14:26:29.302773Z","steps":["trace[881932109] 'process raft request' (duration: 110.207183ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:29.621783Z","caller":"traceutil/trace.go:171","msg":"trace[1653896587] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"141.230938ms","start":"2026-04-20T14:26:29.480526Z","end":"2026-04-20T14:26:29.621757Z","steps":["trace[1653896587] 'process raft request' (duration: 141.111505ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:29.621842Z","caller":"traceutil/trace.go:171","msg":"trace[1427802581] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"141.33281ms","start":"2026-04-20T14:26:29.480472Z","end":"2026-04-20T14:26:29.621805Z","steps":["trace[1427802581] 'process raft request' (duration: 116.986708ms)","trace[1427802581] 'compare' (duration: 23.930823ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:34.00876Z","caller":"traceutil/trace.go:171","msg":"trace[110828056] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"108.995569ms","start":"2026-04-20T14:26:33.899738Z","end":"2026-04-20T14:26:34.008734Z","steps":["trace[110828056] 'process raft request' (duration: 56.607031ms)","trace[110828056] 'compare' (duration: 52.090682ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:34.896391Z","caller":"traceutil/trace.go:171","msg":"trace[1639898569] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"100.235069ms","start":"2026-04-20T14:26:34.796133Z","end":"2026-04-20T14:26:34.896368Z","steps":["trace[1639898569] 'process raft request' (duration: 100.048936ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:35.502675Z","caller":"traceutil/trace.go:171","msg":"trace[509863991] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"380.862092ms","start":"2026-04-20T14:26:35.121789Z","end":"2026-04-20T14:26:35.502651Z","steps":["trace[509863991] 'process raft request' (duration: 380.689549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:35.503104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:35.121758Z","time spent":"381.214889ms","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:35.515268Z","caller":"traceutil/trace.go:171","msg":"trace[666847946] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"183.665516ms","start":"2026-04-20T14:26:35.331583Z","end":"2026-04-20T14:26:35.515248Z","steps":["trace[666847946] 'process raft request' (duration: 183.538954ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:45.738944Z","caller":"traceutil/trace.go:171","msg":"trace[1473410120] linearizableReadLoop","detail":"{readStateIndex:5412; appliedIndex:5411; }","duration":"147.451534ms","start":"2026-04-20T14:26:45.59147Z","end":"2026-04-20T14:26:45.738922Z","steps":["trace[1473410120] 'read index received' (duration: 147.265721ms)","trace[1473410120] 'applied index is now lower than readState.Index' (duration: 184.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:45.738978Z","caller":"traceutil/trace.go:171","msg":"trace[695309338] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"188.330032ms","start":"2026-04-20T14:26:45.550624Z","end":"2026-04-20T14:26:45.738954Z","steps":["trace[695309338] 'process raft request' (duration: 188.135558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:45.739117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.64296ms","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-20T14:26:45.739147Z","caller":"traceutil/trace.go:171","msg":"trace[1260842127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5192; }","duration":"147.720801ms","start":"2026-04-20T14:26:45.591418Z","end":"2026-04-20T14:26:45.739139Z","steps":["trace[1260842127] 'agreement among raft nodes before linearized reading' (duration: 147.606728ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:46.202883Z","caller":"traceutil/trace.go:171","msg":"trace[2122103127] linearizableReadLoop","detail":"{readStateIndex:5416; appliedIndex:5412; }","duration":"458.693598ms","start":"2026-04-20T14:26:45.744169Z","end":"2026-04-20T14:26:46.202863Z","steps":["trace[2122103127] 'read index received' (duration: 34.175547ms)","trace[2122103127] 'applied index is now lower than readState.Index' (duration: 424.51698ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:46.202942Z","caller":"traceutil/trace.go:171","msg":"trace[985295617] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"456.730709ms","start":"2026-04-20T14:26:45.746194Z","end":"2026-04-20T14:26:46.202925Z","steps":["trace[985295617] 'process raft request' (duration: 456.632557ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:46.203086Z","caller":"traceutil/trace.go:171","msg":"trace[671970022] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"458.946403ms","start":"2026-04-20T14:26:45.74397Z","end":"2026-04-20T14:26:46.202916Z","steps":["trace[671970022] 'process raft request' (duration: 458.81731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.203198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.779638ms","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-20T14:26:46.203266Z","caller":"traceutil/trace.go:171","msg":"trace[1750474605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5197; }","duration":"419.894791ms","start":"2026-04-20T14:26:45.783355Z","end":"2026-04-20T14:26:46.20325Z","steps":["trace[1750474605] 'agreement among raft nodes before linearized reading' (duration: 419.712527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.203301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.783336Z","time spent":"419.957972ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:26:46.203417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.833007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-20T14:26:46.203417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.746183Z","time spent":"456.978784ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:46.203446Z","caller":"traceutil/trace.go:171","msg":"trace[1379708484] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5197; }","duration":"405.888508ms","start":"2026-04-20T14:26:45.797548Z","end":"2026-04-20T14:26:46.203436Z","steps":["trace[1379708484] 'agreement among raft nodes before linearized reading' (duration: 405.823227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:46.203024Z","caller":"traceutil/trace.go:171","msg":"trace[1404622870] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"459.279499ms","start":"2026-04-20T14:26:45.743713Z","end":"2026-04-20T14:26:46.202992Z","steps":["trace[1404622870] 'process raft request' (duration: 459.029704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.203472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.797533Z","time spent":"405.933059ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:26:46.203573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.743694Z","time spent":"459.792238ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:46.203569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.74396Z","time spent":"459.317269ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:46.202888Z","caller":"traceutil/trace.go:171","msg":"trace[918466877] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"459.162406ms","start":"2026-04-20T14:26:45.743689Z","end":"2026-04-20T14:26:46.202851Z","steps":["trace[918466877] 'process raft request' (duration: 458.923992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.203109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.921362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11974"} {"level":"info","ts":"2026-04-20T14:26:46.204203Z","caller":"traceutil/trace.go:171","msg":"trace[1564902985] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5197; }","duration":"460.041605ms","start":"2026-04-20T14:26:45.744146Z","end":"2026-04-20T14:26:46.204188Z","steps":["trace[1564902985] 'agreement among raft nodes before linearized reading' (duration: 458.837421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.204267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.744138Z","time spent":"460.115926ms","remote":"127.0.0.1:60300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":11998,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-20T14:26:46.20398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:45.743676Z","time spent":"460.138856ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:46.377426Z","caller":"traceutil/trace.go:171","msg":"trace[1808252325] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5417; }","duration":"167.825066ms","start":"2026-04-20T14:26:46.209575Z","end":"2026-04-20T14:26:46.3774Z","steps":["trace[1808252325] 'read index received' (duration: 91.580782ms)","trace[1808252325] 'applied index is now lower than readState.Index' (duration: 76.243334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:46.377681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.080672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-20T14:26:46.377724Z","caller":"traceutil/trace.go:171","msg":"trace[2111671227] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5198; }","duration":"168.181164ms","start":"2026-04-20T14:26:46.20953Z","end":"2026-04-20T14:26:46.377711Z","steps":["trace[2111671227] 'agreement among raft nodes before linearized reading' (duration: 168.02933ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:46.377661Z","caller":"traceutil/trace.go:171","msg":"trace[1510869906] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"168.070001ms","start":"2026-04-20T14:26:46.209441Z","end":"2026-04-20T14:26:46.377511Z","steps":["trace[1510869906] 'process raft request' (duration: 91.547512ms)","trace[1510869906] 'compare' (duration: 76.251894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:46.653737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.284603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:26:46.654098Z","caller":"traceutil/trace.go:171","msg":"trace[581065953] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"443.361419ms","start":"2026-04-20T14:26:46.210673Z","end":"2026-04-20T14:26:46.654034Z","steps":["trace[581065953] 'process raft request' (duration: 326.696619ms)","trace[581065953] 'compare' (duration: 116.185241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:46.654348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:46.210656Z","time spent":"443.548042ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:46.654419Z","caller":"traceutil/trace.go:171","msg":"trace[5907245] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"443.379179ms","start":"2026-04-20T14:26:46.211014Z","end":"2026-04-20T14:26:46.654393Z","steps":["trace[5907245] 'process raft request' (duration: 442.814018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.65455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:46.211006Z","time spent":"443.47403ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:46.654629Z","caller":"traceutil/trace.go:171","msg":"trace[1078363076] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"402.507423ms","start":"2026-04-20T14:26:46.252009Z","end":"2026-04-20T14:26:46.654517Z","steps":["trace[1078363076] 'process raft request' (duration: 402.036764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.654784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:46.251983Z","time spent":"402.714357ms","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:26:46.654172Z","caller":"traceutil/trace.go:171","msg":"trace[681326467] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5418; }","duration":"276.613954ms","start":"2026-04-20T14:26:46.377534Z","end":"2026-04-20T14:26:46.654148Z","steps":["trace[681326467] 'read index received' (duration: 159.849252ms)","trace[681326467] 'applied index is now lower than readState.Index' (duration: 116.763442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:46.655843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.998863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-20T14:26:46.655899Z","caller":"traceutil/trace.go:171","msg":"trace[1246582467] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5201; }","duration":"385.064554ms","start":"2026-04-20T14:26:46.270822Z","end":"2026-04-20T14:26:46.655887Z","steps":["trace[1246582467] 'agreement among raft nodes before linearized reading' (duration: 384.340441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.656324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:46.270757Z","time spent":"385.530374ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":554,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:26:46.727695Z","caller":"traceutil/trace.go:171","msg":"trace[1148282332] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"344.037625ms","start":"2026-04-20T14:26:46.383632Z","end":"2026-04-20T14:26:46.72767Z","steps":["trace[1148282332] 'process raft request' (duration: 343.633367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.727827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:46.383609Z","time spent":"344.147596ms","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:46.912872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.441264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-20T14:26:46.913208Z","caller":"traceutil/trace.go:171","msg":"trace[140199741] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5202; }","duration":"251.292311ms","start":"2026-04-20T14:26:46.661831Z","end":"2026-04-20T14:26:46.913123Z","steps":["trace[140199741] 'process raft request' (duration: 147.47917ms)","trace[140199741] 'compare' (duration: 103.391642ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:46.913029Z","caller":"traceutil/trace.go:171","msg":"trace[1119136396] linearizableReadLoop","detail":"{readStateIndex:5423; appliedIndex:5422; }","duration":"181.493463ms","start":"2026-04-20T14:26:46.731516Z","end":"2026-04-20T14:26:46.913009Z","steps":["trace[1119136396] 'read index received' (duration: 77.829715ms)","trace[1119136396] 'applied index is now lower than readState.Index' (duration: 103.662348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:46.913404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.87392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-20T14:26:46.913676Z","caller":"traceutil/trace.go:171","msg":"trace[1593447867] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5202; }","duration":"182.183456ms","start":"2026-04-20T14:26:46.731471Z","end":"2026-04-20T14:26:46.913654Z","steps":["trace[1593447867] 'agreement among raft nodes before linearized reading' (duration: 181.783588ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:46.939096Z","caller":"traceutil/trace.go:171","msg":"trace[589018715] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"206.789985ms","start":"2026-04-20T14:26:46.732286Z","end":"2026-04-20T14:26:46.939076Z","steps":["trace[589018715] 'process raft request' (duration: 206.609791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:46.939381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.483782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:26:46.93951Z","caller":"traceutil/trace.go:171","msg":"trace[778572985] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:5203; }","duration":"144.605905ms","start":"2026-04-20T14:26:46.794838Z","end":"2026-04-20T14:26:46.939444Z","steps":["trace[778572985] 'agreement among raft nodes before linearized reading' (duration: 144.268708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:47.139821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.380089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-04-20T14:26:47.139902Z","caller":"traceutil/trace.go:171","msg":"trace[1272589557] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5206; }","duration":"120.501121ms","start":"2026-04-20T14:26:47.019384Z","end":"2026-04-20T14:26:47.139885Z","steps":["trace[1272589557] 'range keys from in-memory index tree' (duration: 120.192605ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:47.942036Z","caller":"traceutil/trace.go:171","msg":"trace[1050839961] linearizableReadLoop","detail":"{readStateIndex:5430; appliedIndex:5429; }","duration":"158.699242ms","start":"2026-04-20T14:26:47.783313Z","end":"2026-04-20T14:26:47.942012Z","steps":["trace[1050839961] 'read index received' (duration: 158.547549ms)","trace[1050839961] 'applied index is now lower than readState.Index' (duration: 150.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:47.94207Z","caller":"traceutil/trace.go:171","msg":"trace[187381039] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"197.574348ms","start":"2026-04-20T14:26:47.744466Z","end":"2026-04-20T14:26:47.94204Z","steps":["trace[187381039] 'process raft request' (duration: 197.410765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:47.942321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.990638ms","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-20T14:26:47.942407Z","caller":"traceutil/trace.go:171","msg":"trace[397150367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5208; }","duration":"159.12499ms","start":"2026-04-20T14:26:47.783263Z","end":"2026-04-20T14:26:47.942388Z","steps":["trace[397150367] 'agreement among raft nodes before linearized reading' (duration: 158.838145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:59.833726Z","caller":"traceutil/trace.go:171","msg":"trace[1654992832] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"150.383745ms","start":"2026-04-20T14:26:59.683317Z","end":"2026-04-20T14:26:59.8337Z","steps":["trace[1654992832] 'process raft request' (duration: 150.223582ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:59.969895Z","caller":"traceutil/trace.go:171","msg":"trace[1923687900] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"124.128267ms","start":"2026-04-20T14:26:59.845744Z","end":"2026-04-20T14:26:59.969873Z","steps":["trace[1923687900] 'process raft request' (duration: 123.922723ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:00.437918Z","caller":"traceutil/trace.go:171","msg":"trace[1922433698] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"457.585871ms","start":"2026-04-20T14:26:59.980297Z","end":"2026-04-20T14:27:00.437883Z","steps":["trace[1922433698] 'process raft request' (duration: 415.421832ms)","trace[1922433698] 'compare' (duration: 41.549997ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:00.437874Z","caller":"traceutil/trace.go:171","msg":"trace[847876808] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5539; }","duration":"399.116352ms","start":"2026-04-20T14:27:00.038733Z","end":"2026-04-20T14:27:00.437849Z","steps":["trace[847876808] 'read index received' (duration: 357.014155ms)","trace[847876808] 'applied index is now lower than readState.Index' (duration: 42.101117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:00.438096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:59.980269Z","time spent":"457.746584ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:27:00.438182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.444439ms","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-20T14:27:00.438254Z","caller":"traceutil/trace.go:171","msg":"trace[863224528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5313; }","duration":"399.583162ms","start":"2026-04-20T14:27:00.038653Z","end":"2026-04-20T14:27:00.438236Z","steps":["trace[863224528] 'agreement among raft nodes before linearized reading' (duration: 399.353697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:00.438294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:00.038632Z","time spent":"399.654083ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:27:00.438385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.352775ms","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-20T14:27:00.438683Z","caller":"traceutil/trace.go:171","msg":"trace[131883608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5313; }","duration":"164.67998ms","start":"2026-04-20T14:27:00.273978Z","end":"2026-04-20T14:27:00.438658Z","steps":["trace[131883608] 'agreement among raft nodes before linearized reading' (duration: 164.220702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:00.438884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.72819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:27:00.438918Z","caller":"traceutil/trace.go:171","msg":"trace[193126453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5313; }","duration":"165.805181ms","start":"2026-04-20T14:27:00.273103Z","end":"2026-04-20T14:27:00.438908Z","steps":["trace[193126453] 'agreement among raft nodes before linearized reading' (duration: 165.705989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:00.439021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.111279ms","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-20T14:27:00.439047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.928614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-20T14:27:00.439094Z","caller":"traceutil/trace.go:171","msg":"trace[1768148626] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5313; }","duration":"175.048226ms","start":"2026-04-20T14:27:00.264034Z","end":"2026-04-20T14:27:00.439082Z","steps":["trace[1768148626] 'agreement among raft nodes before linearized reading' (duration: 174.15014ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:00.439092Z","caller":"traceutil/trace.go:171","msg":"trace[1407564233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5313; }","duration":"174.217651ms","start":"2026-04-20T14:27:00.264854Z","end":"2026-04-20T14:27:00.439072Z","steps":["trace[1407564233] 'agreement among raft nodes before linearized reading' (duration: 174.054838ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:01.217947Z","caller":"traceutil/trace.go:171","msg":"trace[1195801941] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5546; }","duration":"118.976669ms","start":"2026-04-20T14:27:01.098948Z","end":"2026-04-20T14:27:01.217925Z","steps":["trace[1195801941] 'read index received' (duration: 118.716245ms)","trace[1195801941] 'applied index is now lower than readState.Index' (duration: 259.494µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:01.217994Z","caller":"traceutil/trace.go:171","msg":"trace[603847855] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"142.364363ms","start":"2026-04-20T14:27:01.07559Z","end":"2026-04-20T14:27:01.217954Z","steps":["trace[603847855] 'process raft request' (duration: 142.162029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:01.218145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.184664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T14:27:01.218202Z","caller":"traceutil/trace.go:171","msg":"trace[1312556502] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5320; }","duration":"119.286745ms","start":"2026-04-20T14:27:01.098901Z","end":"2026-04-20T14:27:01.218188Z","steps":["trace[1312556502] 'agreement among raft nodes before linearized reading' (duration: 119.125882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:01.218286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.699413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:27:01.218363Z","caller":"traceutil/trace.go:171","msg":"trace[1499899498] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5320; }","duration":"101.833025ms","start":"2026-04-20T14:27:01.116515Z","end":"2026-04-20T14:27:01.218348Z","steps":["trace[1499899498] 'agreement among raft nodes before linearized reading' (duration: 101.664392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:02.22892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.292385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:27:02.229662Z","caller":"traceutil/trace.go:171","msg":"trace[904393471] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"1.004444189s","start":"2026-04-20T14:27:01.225194Z","end":"2026-04-20T14:27:02.229638Z","steps":["trace[904393471] 'process raft request' (duration: 273.336909ms)","trace[904393471] 'compare' (duration: 729.825086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:02.229784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:01.225168Z","time spent":"1.004557622s","remote":"127.0.0.1:60230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:27:02.229859Z","caller":"traceutil/trace.go:171","msg":"trace[1171417113] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"1.004328997s","start":"2026-04-20T14:27:01.225499Z","end":"2026-04-20T14:27:02.229828Z","steps":["trace[1171417113] 'process raft request' (duration: 1.003545493s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:02.230002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:01.22548Z","time spent":"1.004442819s","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:27:02.23077Z","caller":"traceutil/trace.go:171","msg":"trace[1714789217] linearizableReadLoop","detail":"{readStateIndex:5549; appliedIndex:5547; }","duration":"846.072705ms","start":"2026-04-20T14:27:01.38307Z","end":"2026-04-20T14:27:02.229142Z","steps":["trace[1714789217] 'read index received' (duration: 115.600666ms)","trace[1714789217] 'applied index is now lower than readState.Index' (duration: 730.470849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:02.230832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.763417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T14:27:02.230888Z","caller":"traceutil/trace.go:171","msg":"trace[1841764878] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5322; }","duration":"847.855569ms","start":"2026-04-20T14:27:01.383014Z","end":"2026-04-20T14:27:02.23087Z","steps":["trace[1841764878] 'agreement among raft nodes before linearized reading' (duration: 847.687646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:02.230944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:01.382995Z","time spent":"847.93604ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-20T14:27:02.478083Z","caller":"traceutil/trace.go:171","msg":"trace[63510336] linearizableReadLoop","detail":"{readStateIndex:5550; appliedIndex:5549; }","duration":"247.213797ms","start":"2026-04-20T14:27:02.230837Z","end":"2026-04-20T14:27:02.478051Z","steps":["trace[63510336] 'read index received' (duration: 197.646611ms)","trace[63510336] 'applied index is now lower than readState.Index' (duration: 49.563936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:02.478173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.658221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:27:02.478208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.459923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:27:02.478258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.294233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:27:02.478256Z","caller":"traceutil/trace.go:171","msg":"trace[1030343972] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:5322; }","duration":"520.786513ms","start":"2026-04-20T14:27:01.957445Z","end":"2026-04-20T14:27:02.478232Z","steps":["trace[1030343972] 'agreement among raft nodes before linearized reading' (duration: 520.64271ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:02.478266Z","caller":"traceutil/trace.go:171","msg":"trace[476067675] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5322; }","duration":"616.570004ms","start":"2026-04-20T14:27:01.861679Z","end":"2026-04-20T14:27:02.478249Z","steps":["trace[476067675] 'agreement among raft nodes before linearized reading' (duration: 616.450582ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:02.478296Z","caller":"traceutil/trace.go:171","msg":"trace[306901975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5323; }","duration":"797.376765ms","start":"2026-04-20T14:27:01.68091Z","end":"2026-04-20T14:27:02.478287Z","steps":["trace[306901975] 'agreement among raft nodes before linearized reading' (duration: 797.271463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:02.478313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:01.86166Z","time spent":"616.643346ms","remote":"127.0.0.1:43052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:27:02.478331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:01.680886Z","time spent":"797.438506ms","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:27:02.478307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:27:01.957424Z","time spent":"520.872275ms","remote":"127.0.0.1:41908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":5,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:27:02.478219Z","caller":"traceutil/trace.go:171","msg":"trace[2061909215] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"242.456967ms","start":"2026-04-20T14:27:02.235738Z","end":"2026-04-20T14:27:02.478195Z","steps":["trace[2061909215] 'process raft request' (duration: 242.225943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:02.484715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.73884ms","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-20T14:27:02.48477Z","caller":"traceutil/trace.go:171","msg":"trace[515997386] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5324; }","duration":"245.823731ms","start":"2026-04-20T14:27:02.238933Z","end":"2026-04-20T14:27:02.484757Z","steps":["trace[515997386] 'agreement among raft nodes before linearized reading' (duration: 245.730869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:27:02.485031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.752853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-20T14:27:02.485091Z","caller":"traceutil/trace.go:171","msg":"trace[256695767] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5324; }","duration":"251.833855ms","start":"2026-04-20T14:27:02.23324Z","end":"2026-04-20T14:27:02.485074Z","steps":["trace[256695767] 'agreement among raft nodes before linearized reading' (duration: 251.657142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:24.757751Z","caller":"traceutil/trace.go:171","msg":"trace[1483802221] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5958; }","duration":"130.272809ms","start":"2026-04-20T14:28:24.62745Z","end":"2026-04-20T14:28:24.757722Z","steps":["trace[1483802221] 'process raft request' (duration: 74.118185ms)","trace[1483802221] 'compare' (duration: 56.091383ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:29:03.836576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3200} {"level":"info","ts":"2026-04-20T14:29:03.932517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3200,"took":"94.682624ms","hash":1859877494,"current-db-size-bytes":58839040,"current-db-size":"59 MB","current-db-size-in-use-bytes":31141888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-20T14:29:03.932611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1859877494,"revision":3200,"compact-revision":-1} {"level":"info","ts":"2026-04-20T14:30:07.543739Z","caller":"traceutil/trace.go:171","msg":"trace[1091172128] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6882; }","duration":"161.189905ms","start":"2026-04-20T14:30:07.382533Z","end":"2026-04-20T14:30:07.543723Z","steps":["trace[1091172128] 'process raft request' (duration: 160.073867ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:27.6547Z","caller":"traceutil/trace.go:171","msg":"trace[298387500] transaction","detail":"{read_only:false; response_revision:7014; number_of_response:1; }","duration":"234.5066ms","start":"2026-04-20T14:30:27.420171Z","end":"2026-04-20T14:30:27.654677Z","steps":["trace[298387500] 'process raft request' (duration: 234.341868ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.435121Z","caller":"traceutil/trace.go:171","msg":"trace[262268239] transaction","detail":"{read_only:false; response_revision:7089; number_of_response:1; }","duration":"143.178513ms","start":"2026-04-20T14:30:38.29192Z","end":"2026-04-20T14:30:38.435098Z","steps":["trace[262268239] 'process raft request' (duration: 142.571814ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.578588Z","caller":"traceutil/trace.go:171","msg":"trace[56744248] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7090; }","duration":"120.657818ms","start":"2026-04-20T14:30:38.457909Z","end":"2026-04-20T14:30:38.578567Z","steps":["trace[56744248] 'process raft request' (duration: 120.572107ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.667544Z","caller":"traceutil/trace.go:171","msg":"trace[1925763448] transaction","detail":"{read_only:false; response_revision:7092; number_of_response:1; }","duration":"138.595632ms","start":"2026-04-20T14:30:38.52893Z","end":"2026-04-20T14:30:38.667525Z","steps":["trace[1925763448] 'process raft request' (duration: 138.44035ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.667692Z","caller":"traceutil/trace.go:171","msg":"trace[1388168847] transaction","detail":"{read_only:false; response_revision:7091; number_of_response:1; }","duration":"138.763735ms","start":"2026-04-20T14:30:38.5289Z","end":"2026-04-20T14:30:38.667664Z","steps":["trace[1388168847] 'process raft request' (duration: 113.452187ms)","trace[1388168847] 'compare' (duration: 24.830971ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:38.668054Z","caller":"traceutil/trace.go:171","msg":"trace[521956434] transaction","detail":"{read_only:false; response_revision:7094; number_of_response:1; }","duration":"138.932948ms","start":"2026-04-20T14:30:38.529106Z","end":"2026-04-20T14:30:38.668039Z","steps":["trace[521956434] 'process raft request' (duration: 138.374879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:38.668352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.201957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T14:30:38.668393Z","caller":"traceutil/trace.go:171","msg":"trace[600904375] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7095; }","duration":"125.289559ms","start":"2026-04-20T14:30:38.543092Z","end":"2026-04-20T14:30:38.668381Z","steps":["trace[600904375] 'agreement among raft nodes before linearized reading' (duration: 125.122236ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.66774Z","caller":"traceutil/trace.go:171","msg":"trace[1326209108] transaction","detail":"{read_only:false; response_revision:7093; number_of_response:1; }","duration":"138.719164ms","start":"2026-04-20T14:30:38.529013Z","end":"2026-04-20T14:30:38.667732Z","steps":["trace[1326209108] 'process raft request' (duration: 138.42675ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.831184Z","caller":"traceutil/trace.go:171","msg":"trace[1690331012] transaction","detail":"{read_only:false; response_revision:7103; number_of_response:1; }","duration":"112.93307ms","start":"2026-04-20T14:30:38.718224Z","end":"2026-04-20T14:30:38.831157Z","steps":["trace[1690331012] 'process raft request' (duration: 69.587716ms)","trace[1690331012] 'compare' (duration: 43.231642ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:38.839957Z","caller":"traceutil/trace.go:171","msg":"trace[573160652] transaction","detail":"{read_only:false; response_revision:7104; number_of_response:1; }","duration":"116.741059ms","start":"2026-04-20T14:30:38.723196Z","end":"2026-04-20T14:30:38.839937Z","steps":["trace[573160652] 'process raft request' (duration: 116.181ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.840186Z","caller":"traceutil/trace.go:171","msg":"trace[1663202626] transaction","detail":"{read_only:false; response_revision:7105; number_of_response:1; }","duration":"113.831414ms","start":"2026-04-20T14:30:38.726346Z","end":"2026-04-20T14:30:38.840177Z","steps":["trace[1663202626] 'process raft request' (duration: 113.164284ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.992853Z","caller":"traceutil/trace.go:171","msg":"trace[2139607847] transaction","detail":"{read_only:false; response_revision:7107; number_of_response:1; }","duration":"140.940929ms","start":"2026-04-20T14:30:38.851882Z","end":"2026-04-20T14:30:38.992823Z","steps":["trace[2139607847] 'process raft request' (duration: 107.338054ms)","trace[2139607847] 'compare' (duration: 33.407282ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:38.99315Z","caller":"traceutil/trace.go:171","msg":"trace[851978827] linearizableReadLoop","detail":"{readStateIndex:7431; appliedIndex:7428; }","duration":"139.636759ms","start":"2026-04-20T14:30:38.853493Z","end":"2026-04-20T14:30:38.99313Z","steps":["trace[851978827] 'read index received' (duration: 38.816875ms)","trace[851978827] 'applied index is now lower than readState.Index' (duration: 100.819234ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:38.993242Z","caller":"traceutil/trace.go:171","msg":"trace[1549908931] transaction","detail":"{read_only:false; response_revision:7109; number_of_response:1; }","duration":"138.138145ms","start":"2026-04-20T14:30:38.855071Z","end":"2026-04-20T14:30:38.993209Z","steps":["trace[1549908931] 'process raft request' (duration: 137.978493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:38.99335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.823852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-04-20T14:30:38.99338Z","caller":"traceutil/trace.go:171","msg":"trace[1624569916] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7109; }","duration":"139.910414ms","start":"2026-04-20T14:30:38.853458Z","end":"2026-04-20T14:30:38.993369Z","steps":["trace[1624569916] 'agreement among raft nodes before linearized reading' (duration: 139.778302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:38.993385Z","caller":"traceutil/trace.go:171","msg":"trace[1322100940] transaction","detail":"{read_only:false; response_revision:7108; number_of_response:1; }","duration":"141.236623ms","start":"2026-04-20T14:30:38.852016Z","end":"2026-04-20T14:30:38.993253Z","steps":["trace[1322100940] 'process raft request' (duration: 140.715045ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:39.112876Z","caller":"traceutil/trace.go:171","msg":"trace[17829454] transaction","detail":"{read_only:false; response_revision:7111; number_of_response:1; }","duration":"108.007684ms","start":"2026-04-20T14:30:39.004843Z","end":"2026-04-20T14:30:39.112851Z","steps":["trace[17829454] 'process raft request' (duration: 99.201799ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:40.177643Z","caller":"traceutil/trace.go:171","msg":"trace[2038721850] transaction","detail":"{read_only:false; response_revision:7119; number_of_response:1; }","duration":"106.095864ms","start":"2026-04-20T14:30:40.071521Z","end":"2026-04-20T14:30:40.177616Z","steps":["trace[2038721850] 'process raft request' (duration: 43.72122ms)","trace[2038721850] 'compare' (duration: 62.267473ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:31:38.465882Z","caller":"traceutil/trace.go:171","msg":"trace[449671476] transaction","detail":"{read_only:false; response_revision:7493; number_of_response:1; }","duration":"149.478674ms","start":"2026-04-20T14:31:38.316386Z","end":"2026-04-20T14:31:38.465864Z","steps":["trace[449671476] 'process raft request' (duration: 124.701482ms)","trace[449671476] 'compare' (duration: 24.306986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:32:42.807932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.491766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:32:42.808367Z","caller":"traceutil/trace.go:171","msg":"trace[1347589354] transaction","detail":"{read_only:false; response_revision:7905; number_of_response:1; }","duration":"211.721916ms","start":"2026-04-20T14:32:42.596623Z","end":"2026-04-20T14:32:42.808345Z","steps":["trace[1347589354] 'process raft request' (duration: 81.722692ms)","trace[1347589354] 'compare' (duration: 129.231027ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:42.808373Z","caller":"traceutil/trace.go:171","msg":"trace[1381435678] linearizableReadLoop","detail":"{readStateIndex:8277; appliedIndex:8276; }","duration":"210.156961ms","start":"2026-04-20T14:32:42.598191Z","end":"2026-04-20T14:32:42.808348Z","steps":["trace[1381435678] 'read index received' (duration: 80.140647ms)","trace[1381435678] 'applied index is now lower than readState.Index' (duration: 130.014134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:32:42.808683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.417565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-pool-overview\" ","response":"range_response_count:1 size:49605"} {"level":"info","ts":"2026-04-20T14:32:42.808736Z","caller":"traceutil/trace.go:171","msg":"trace[1631856226] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-pool-overview; range_end:; response_count:1; response_revision:7906; }","duration":"136.483917ms","start":"2026-04-20T14:32:42.672235Z","end":"2026-04-20T14:32:42.808719Z","steps":["trace[1631856226] 'agreement among raft nodes before linearized reading' (duration: 136.247799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:42.808812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.490137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:32:42.808856Z","caller":"traceutil/trace.go:171","msg":"trace[1458104675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7906; }","duration":"129.574269ms","start":"2026-04-20T14:32:42.679269Z","end":"2026-04-20T14:32:42.808843Z","steps":["trace[1458104675] 'agreement among raft nodes before linearized reading' (duration: 129.500397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:42.809636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.418915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/\" range_end:\"/registry/events/monitoring0\" limit:500 ","response":"range_response_count:174 size:144054"} {"level":"info","ts":"2026-04-20T14:32:42.809736Z","caller":"traceutil/trace.go:171","msg":"trace[361676193] range","detail":"{range_begin:/registry/events/monitoring/; range_end:/registry/events/monitoring0; response_count:174; response_revision:7906; }","duration":"211.525949ms","start":"2026-04-20T14:32:42.598186Z","end":"2026-04-20T14:32:42.809712Z","steps":["trace[361676193] 'agreement among raft nodes before linearized reading' (duration: 210.282047ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:42.953857Z","caller":"traceutil/trace.go:171","msg":"trace[140304803] transaction","detail":"{read_only:false; response_revision:7907; number_of_response:1; }","duration":"140.36839ms","start":"2026-04-20T14:32:42.813459Z","end":"2026-04-20T14:32:42.953827Z","steps":["trace[140304803] 'process raft request' (duration: 113.293299ms)","trace[140304803] 'compare' (duration: 26.713879ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:42.954178Z","caller":"traceutil/trace.go:171","msg":"trace[1380551385] transaction","detail":"{read_only:false; response_revision:7908; number_of_response:1; }","duration":"139.944396ms","start":"2026-04-20T14:32:42.814228Z","end":"2026-04-20T14:32:42.954172Z","steps":["trace[1380551385] 'process raft request' (duration: 139.52292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:43.217154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.144781ms","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-20T14:32:43.217238Z","caller":"traceutil/trace.go:171","msg":"trace[337825072] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7908; }","duration":"151.271885ms","start":"2026-04-20T14:32:43.065947Z","end":"2026-04-20T14:32:43.217219Z","steps":["trace[337825072] 'count revisions from in-memory index tree' (duration: 151.077378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:44.286119Z","caller":"traceutil/trace.go:171","msg":"trace[1153429841] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"127.288382ms","start":"2026-04-20T14:32:44.158805Z","end":"2026-04-20T14:32:44.286094Z","steps":["trace[1153429841] 'process raft request' (duration: 127.099056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:44.288122Z","caller":"traceutil/trace.go:171","msg":"trace[1398769349] linearizableReadLoop","detail":"{readStateIndex:8282; appliedIndex:8280; }","duration":"114.273727ms","start":"2026-04-20T14:32:44.17382Z","end":"2026-04-20T14:32:44.288094Z","steps":["trace[1398769349] 'read index received' (duration: 112.119643ms)","trace[1398769349] 'applied index is now lower than readState.Index' (duration: 2.152894ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:44.288289Z","caller":"traceutil/trace.go:171","msg":"trace[2086327306] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"128.07464ms","start":"2026-04-20T14:32:44.160198Z","end":"2026-04-20T14:32:44.288272Z","steps":["trace[2086327306] 'process raft request' (duration: 127.719338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:44.288517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.67571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-k8s-resources-node\" ","response":"range_response_count:1 size:14881"} {"level":"info","ts":"2026-04-20T14:32:44.288617Z","caller":"traceutil/trace.go:171","msg":"trace[45006095] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-k8s-resources-node; range_end:; response_count:1; response_revision:7910; }","duration":"114.809495ms","start":"2026-04-20T14:32:44.173784Z","end":"2026-04-20T14:32:44.288593Z","steps":["trace[45006095] 'agreement among raft nodes before linearized reading' (duration: 114.539266ms)"],"step_count":1}