{"level":"warn","ts":"2026-05-11T00:51:18.339378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:51:18.339498Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-11T00:51:18.339569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T00:51:18.339586Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-11T00:51:18.33961Z","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-05-11T00:51:18.340516Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-11T00:51:18.340601Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-11T00:51:18.34637Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.309905ms"} {"level":"info","ts":"2026-05-11T00:51:18.357164Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-11T00:51:18.357478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T00:51:18.357523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-11T00:51:18.357535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T00:51:18.357551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-11T00:51:18.357737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-11T00:51:18.364828Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T00:51:18.366172Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T00:51:18.367437Z","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-05-11T00:51:18.370177Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T00:51:18.371656Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T00:51:18.370869Z","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-05-11T00:51:18.372064Z","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-05-11T00:51:18.372104Z","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-05-11T00:51:18.373055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-11T00:51:18.373293Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-11T00:51:18.377233Z","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-05-11T00:51:18.377368Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-11T00:51:18.377404Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-11T00:51:18.382625Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T00:51:18.382712Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T00:51:18.658717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T00:51:18.658785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T00:51:18.658824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-11T00:51:18.65884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-11T00:51:18.658848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-11T00:51:18.658859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-11T00:51:18.658869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-11T00:51:18.659903Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:18.66046Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T00:51:18.6606Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:51:18.660827Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T00:51:18.661197Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:18.661386Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:18.661427Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T00:51:18.662345Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T00:51:18.662369Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T00:51:18.664063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T00:51:18.665078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-11T00:51:24.522613Z","caller":"traceutil/trace.go:171","msg":"trace[5727485] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"106.444705ms","start":"2026-05-11T00:51:24.416133Z","end":"2026-05-11T00:51:24.522578Z","steps":["trace[5727485] 'process raft request' (duration: 106.242547ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:48.771427Z","caller":"traceutil/trace.go:171","msg":"trace[554008284] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"116.499992ms","start":"2026-05-11T00:51:48.654903Z","end":"2026-05-11T00:51:48.771403Z","steps":["trace[554008284] 'process raft request' (duration: 110.196468ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:48.772202Z","caller":"traceutil/trace.go:171","msg":"trace[2085847399] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"105.418051ms","start":"2026-05-11T00:51:48.666725Z","end":"2026-05-11T00:51:48.772143Z","steps":["trace[2085847399] 'process raft request' (duration: 105.104396ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:54.618058Z","caller":"traceutil/trace.go:171","msg":"trace[1225972902] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"394.925842ms","start":"2026-05-11T00:51:54.223109Z","end":"2026-05-11T00:51:54.618035Z","steps":["trace[1225972902] 'process raft request' (duration: 394.782185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:54.61863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:54.223091Z","time spent":"395.049069ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:54.878492Z","caller":"traceutil/trace.go:171","msg":"trace[2014140798] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"451.563096ms","start":"2026-05-11T00:51:54.426904Z","end":"2026-05-11T00:51:54.878467Z","steps":["trace[2014140798] 'process raft request' (duration: 449.950474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:54.879204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:54.426853Z","time spent":"452.2442ms","remote":"127.0.0.1:41834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:51:54.905096Z","caller":"traceutil/trace.go:171","msg":"trace[1074362703] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"102.624916ms","start":"2026-05-11T00:51:54.802404Z","end":"2026-05-11T00:51:54.905029Z","steps":["trace[1074362703] 'read index received' (duration: 74.317459ms)","trace[1074362703] 'applied index is now lower than readState.Index' (duration: 28.306567ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:51:54.905181Z","caller":"traceutil/trace.go:171","msg":"trace[538825147] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"136.335056ms","start":"2026-05-11T00:51:54.768834Z","end":"2026-05-11T00:51:54.905169Z","steps":["trace[538825147] 'process raft request' (duration: 135.948936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:54.905273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.874248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T00:51:54.905362Z","caller":"traceutil/trace.go:171","msg":"trace[1422080325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"103.020216ms","start":"2026-05-11T00:51:54.802333Z","end":"2026-05-11T00:51:54.905354Z","steps":["trace[1422080325] 'agreement among raft nodes before linearized reading' (duration: 102.833617ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:55.460651Z","caller":"traceutil/trace.go:171","msg":"trace[1266644078] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"268.650658ms","start":"2026-05-11T00:51:55.191972Z","end":"2026-05-11T00:51:55.460623Z","steps":["trace[1266644078] 'read index received' (duration: 268.446157ms)","trace[1266644078] 'applied index is now lower than readState.Index' (duration: 203.411µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:51:55.460931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.936952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T00:51:55.461036Z","caller":"traceutil/trace.go:171","msg":"trace[1090412375] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"516.650296ms","start":"2026-05-11T00:51:54.944209Z","end":"2026-05-11T00:51:55.460859Z","steps":["trace[1090412375] 'process raft request' (duration: 516.259486ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:51:55.461026Z","caller":"traceutil/trace.go:171","msg":"trace[1755985998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"269.112561ms","start":"2026-05-11T00:51:55.191893Z","end":"2026-05-11T00:51:55.461005Z","steps":["trace[1755985998] 'agreement among raft nodes before linearized reading' (duration: 268.841827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:51:55.461433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:51:54.944188Z","time spent":"517.057176ms","remote":"127.0.0.1:42226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:52:10.915118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.458481ms","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-05-11T00:52:10.91524Z","caller":"traceutil/trace.go:171","msg":"trace[1103736143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"154.628229ms","start":"2026-05-11T00:52:10.760592Z","end":"2026-05-11T00:52:10.915221Z","steps":["trace[1103736143] 'range keys from in-memory index tree' (duration: 154.239158ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:52:15.123468Z","caller":"traceutil/trace.go:171","msg":"trace[481070762] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"101.252382ms","start":"2026-05-11T00:52:15.022188Z","end":"2026-05-11T00:52:15.12344Z","steps":["trace[481070762] 'process raft request' (duration: 55.554547ms)","trace[481070762] 'compare' (duration: 45.531826ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:26.11818Z","caller":"traceutil/trace.go:171","msg":"trace[1676926532] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"104.317452ms","start":"2026-05-11T00:53:26.013839Z","end":"2026-05-11T00:53:26.118157Z","steps":["trace[1676926532] 'process raft request' (duration: 104.157019ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:26.467039Z","caller":"traceutil/trace.go:171","msg":"trace[668997981] linearizableReadLoop","detail":"{readStateIndex:1866; appliedIndex:1865; }","duration":"184.063002ms","start":"2026-05-11T00:53:26.282948Z","end":"2026-05-11T00:53:26.467011Z","steps":["trace[668997981] 'read index received' (duration: 183.832097ms)","trace[668997981] 'applied index is now lower than readState.Index' (duration: 229.575µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:26.467316Z","caller":"traceutil/trace.go:171","msg":"trace[455249153] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"384.898951ms","start":"2026-05-11T00:53:26.082388Z","end":"2026-05-11T00:53:26.467287Z","steps":["trace[455249153] 'process raft request' (duration: 384.432111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:53:26.467488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:53:26.082361Z","time spent":"385.047795ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:53:26.46767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.696677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-11T00:53:26.467827Z","caller":"traceutil/trace.go:171","msg":"trace[1968126311] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1799; }","duration":"184.80795ms","start":"2026-05-11T00:53:26.282902Z","end":"2026-05-11T00:53:26.46771Z","steps":["trace[1968126311] 'agreement among raft nodes before linearized reading' (duration: 184.440651ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:26.928962Z","caller":"traceutil/trace.go:171","msg":"trace[1650762249] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"131.224177ms","start":"2026-05-11T00:53:26.797718Z","end":"2026-05-11T00:53:26.928942Z","steps":["trace[1650762249] 'read index received' (duration: 122.159014ms)","trace[1650762249] 'applied index is now lower than readState.Index' (duration: 9.064213ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:53:26.929076Z","caller":"traceutil/trace.go:171","msg":"trace[1559006958] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"246.385611ms","start":"2026-05-11T00:53:26.682679Z","end":"2026-05-11T00:53:26.929065Z","steps":["trace[1559006958] 'process raft request' (duration: 237.157155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:53:26.929554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.8119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-11T00:53:26.929678Z","caller":"traceutil/trace.go:171","msg":"trace[429974652] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1805; }","duration":"131.969933ms","start":"2026-05-11T00:53:26.797687Z","end":"2026-05-11T00:53:26.929657Z","steps":["trace[429974652] 'agreement among raft nodes before linearized reading' (duration: 131.689056ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:39.768305Z","caller":"traceutil/trace.go:171","msg":"trace[1150860951] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"113.595444ms","start":"2026-05-11T00:53:39.654692Z","end":"2026-05-11T00:53:39.768287Z","steps":["trace[1150860951] 'process raft request' (duration: 113.488721ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:53:39.779702Z","caller":"traceutil/trace.go:171","msg":"trace[1745892010] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"122.69899ms","start":"2026-05-11T00:53:39.656984Z","end":"2026-05-11T00:53:39.779683Z","steps":["trace[1745892010] 'process raft request' (duration: 122.518715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:02.359766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.312668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:54:02.360295Z","caller":"traceutil/trace.go:171","msg":"trace[249911275] linearizableReadLoop","detail":"{readStateIndex:2125; appliedIndex:2123; }","duration":"434.468994ms","start":"2026-05-11T00:54:01.925809Z","end":"2026-05-11T00:54:02.360278Z","steps":["trace[249911275] 'read index received' (duration: 60.651652ms)","trace[249911275] 'applied index is now lower than readState.Index' (duration: 373.816482ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:02.360275Z","caller":"traceutil/trace.go:171","msg":"trace[521526301] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"466.296555ms","start":"2026-05-11T00:54:01.893938Z","end":"2026-05-11T00:54:02.360235Z","steps":["trace[521526301] 'process raft request' (duration: 327.286064ms)","trace[521526301] 'compare' (duration: 137.7712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:54:02.360418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.600038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:02.360511Z","caller":"traceutil/trace.go:171","msg":"trace[1111246481] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"379.633515ms","start":"2026-05-11T00:54:01.980731Z","end":"2026-05-11T00:54:02.360365Z","steps":["trace[1111246481] 'process raft request' (duration: 379.130548ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.360592Z","caller":"traceutil/trace.go:171","msg":"trace[1792978120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2052; }","duration":"434.774574ms","start":"2026-05-11T00:54:01.925791Z","end":"2026-05-11T00:54:02.360566Z","steps":["trace[1792978120] 'agreement among raft nodes before linearized reading' (duration: 434.569487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:02.360636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:01.925784Z","time spent":"434.841436ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:54:02.360722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:01.893912Z","time spent":"466.575185ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:02.360796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:01.980707Z","time spent":"380.006907ms","remote":"127.0.0.1:41818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:54:02.429988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.680073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T00:54:02.430042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.844987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-11T00:54:02.430045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.084887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-11T00:54:02.430323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.016225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-05-11T00:54:02.430098Z","caller":"traceutil/trace.go:171","msg":"trace[616507465] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:2053; }","duration":"231.958331ms","start":"2026-05-11T00:54:02.198124Z","end":"2026-05-11T00:54:02.430083Z","steps":["trace[616507465] 'agreement among raft nodes before linearized reading' (duration: 231.746484ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.430479Z","caller":"traceutil/trace.go:171","msg":"trace[1555266873] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2053; }","duration":"279.250643ms","start":"2026-05-11T00:54:02.151202Z","end":"2026-05-11T00:54:02.430453Z","steps":["trace[1555266873] 'agreement among raft nodes before linearized reading' (duration: 278.709665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.430731Z","caller":"traceutil/trace.go:171","msg":"trace[548584781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2053; }","duration":"248.214491ms","start":"2026-05-11T00:54:02.181922Z","end":"2026-05-11T00:54:02.430136Z","steps":["trace[548584781] 'agreement among raft nodes before linearized reading' (duration: 248.002964ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.430356Z","caller":"traceutil/trace.go:171","msg":"trace[1959335143] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"136.171156ms","start":"2026-05-11T00:54:02.294048Z","end":"2026-05-11T00:54:02.430219Z","steps":["trace[1959335143] 'process raft request' (duration: 135.689801ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.430056Z","caller":"traceutil/trace.go:171","msg":"trace[341332067] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2053; }","duration":"145.804427ms","start":"2026-05-11T00:54:02.284237Z","end":"2026-05-11T00:54:02.430041Z","steps":["trace[341332067] 'agreement among raft nodes before linearized reading' (duration: 145.60615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:02.772784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.232559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:54:02.773235Z","caller":"traceutil/trace.go:171","msg":"trace[345807102] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"313.47048ms","start":"2026-05-11T00:54:02.459744Z","end":"2026-05-11T00:54:02.773215Z","steps":["trace[345807102] 'process raft request' (duration: 313.182671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:02.773324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:02.459725Z","time spent":"313.559773ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:02.773421Z","caller":"traceutil/trace.go:171","msg":"trace[777697022] linearizableReadLoop","detail":"{readStateIndex:2132; appliedIndex:2128; }","duration":"124.948513ms","start":"2026-05-11T00:54:02.648404Z","end":"2026-05-11T00:54:02.773353Z","steps":["trace[777697022] 'read index received' (duration: 16.160548ms)","trace[777697022] 'applied index is now lower than readState.Index' (duration: 108.786905ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:02.773473Z","caller":"traceutil/trace.go:171","msg":"trace[2087068095] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"166.828965ms","start":"2026-05-11T00:54:02.606587Z","end":"2026-05-11T00:54:02.773416Z","steps":["trace[2087068095] 'process raft request' (duration: 166.541106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:02.773708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.266458ms","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-05-11T00:54:02.773745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.321766ms","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-05-11T00:54:02.773753Z","caller":"traceutil/trace.go:171","msg":"trace[427463371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2057; }","duration":"120.33246ms","start":"2026-05-11T00:54:02.65341Z","end":"2026-05-11T00:54:02.773743Z","steps":["trace[427463371] 'agreement among raft nodes before linearized reading' (duration: 120.204615ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.773784Z","caller":"traceutil/trace.go:171","msg":"trace[642542638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2057; }","duration":"125.403159ms","start":"2026-05-11T00:54:02.64837Z","end":"2026-05-11T00:54:02.773773Z","steps":["trace[642542638] 'agreement among raft nodes before linearized reading' (duration: 125.188301ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:02.773868Z","caller":"traceutil/trace.go:171","msg":"trace[1606336692] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"338.290716ms","start":"2026-05-11T00:54:02.435567Z","end":"2026-05-11T00:54:02.773858Z","steps":["trace[1606336692] 'process raft request' (duration: 337.303112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:02.773997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:02.435558Z","time spent":"338.404089ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:02.773988Z","caller":"traceutil/trace.go:171","msg":"trace[632099755] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"339.184394ms","start":"2026-05-11T00:54:02.434782Z","end":"2026-05-11T00:54:02.773967Z","steps":["trace[632099755] 'process raft request' (duration: 213.682082ms)","trace[632099755] 'compare' (duration: 124.094435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:54:02.774226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:02.434759Z","time spent":"339.35169ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:54:38.715153Z","caller":"traceutil/trace.go:171","msg":"trace[2076954332] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"190.135834ms","start":"2026-05-11T00:54:38.524984Z","end":"2026-05-11T00:54:38.715119Z","steps":["trace[2076954332] 'process raft request' (duration: 190.006419ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:38.716878Z","caller":"traceutil/trace.go:171","msg":"trace[777281429] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"172.464199ms","start":"2026-05-11T00:54:38.544399Z","end":"2026-05-11T00:54:38.716864Z","steps":["trace[777281429] 'process raft request' (duration: 172.397006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:55.272014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.938963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/memcached-memcached\" ","response":"range_response_count:1 size:543"} {"level":"info","ts":"2026-05-11T00:54:55.272087Z","caller":"traceutil/trace.go:171","msg":"trace[1594774920] range","detail":"{range_begin:/registry/serviceaccounts/openstack/memcached-memcached; range_end:; response_count:1; response_revision:2373; }","duration":"340.059538ms","start":"2026-05-11T00:54:54.932012Z","end":"2026-05-11T00:54:55.272072Z","steps":["trace[1594774920] 'range keys from in-memory index tree' (duration: 339.771566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:55.272235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.097758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:54:55.272316Z","caller":"traceutil/trace.go:171","msg":"trace[2110709488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2373; }","duration":"346.217483ms","start":"2026-05-11T00:54:54.926079Z","end":"2026-05-11T00:54:55.272297Z","steps":["trace[2110709488] 'range keys from in-memory index tree' (duration: 346.020055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:55.272309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.115716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:6 size:224676"} {"level":"warn","ts":"2026-05-11T00:54:55.272373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:54.926061Z","time spent":"346.303647ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T00:54:55.272387Z","caller":"traceutil/trace.go:171","msg":"trace[10004925] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:6; response_revision:2373; }","duration":"414.248722ms","start":"2026-05-11T00:54:54.858122Z","end":"2026-05-11T00:54:55.272371Z","steps":["trace[10004925] 'range keys from in-memory index tree' (duration: 413.805692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:55.272429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:54.8581Z","time spent":"414.319935ms","remote":"127.0.0.1:41834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":6,"response size":224699,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-05-11T00:54:55.272282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:54.931993Z","time spent":"340.275568ms","remote":"127.0.0.1:41920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":566,"request content":"key:\"/registry/serviceaccounts/openstack/memcached-memcached\" "} {"level":"warn","ts":"2026-05-11T00:54:55.27274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.11772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:54:55.27295Z","caller":"traceutil/trace.go:171","msg":"trace[501035254] linearizableReadLoop","detail":"{readStateIndex:2462; appliedIndex:2460; }","duration":"271.296169ms","start":"2026-05-11T00:54:55.001641Z","end":"2026-05-11T00:54:55.272937Z","steps":["trace[501035254] 'read index received' (duration: 154.875857ms)","trace[501035254] 'applied index is now lower than readState.Index' (duration: 116.419432ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:54:55.27307Z","caller":"traceutil/trace.go:171","msg":"trace[901677528] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"332.322551ms","start":"2026-05-11T00:54:54.940735Z","end":"2026-05-11T00:54:55.273057Z","steps":["trace[901677528] 'process raft request' (duration: 215.765623ms)","trace[901677528] 'compare' (duration: 115.561556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:54:55.273196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:54:54.940715Z","time spent":"332.445226ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:54:55.27333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.680726ms","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-05-11T00:54:55.273376Z","caller":"traceutil/trace.go:171","msg":"trace[2019175606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2375; }","duration":"271.751278ms","start":"2026-05-11T00:54:55.001613Z","end":"2026-05-11T00:54:55.273365Z","steps":["trace[2019175606] 'agreement among raft nodes before linearized reading' (duration: 271.621473ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:54:55.273493Z","caller":"traceutil/trace.go:171","msg":"trace[861445591] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"280.008018ms","start":"2026-05-11T00:54:54.993476Z","end":"2026-05-11T00:54:55.273484Z","steps":["trace[861445591] 'process raft request' (duration: 279.373212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:55.273967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.849546ms","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-05-11T00:54:55.274027Z","caller":"traceutil/trace.go:171","msg":"trace[798717498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2375; }","duration":"210.958091ms","start":"2026-05-11T00:54:55.063051Z","end":"2026-05-11T00:54:55.274009Z","steps":["trace[798717498] 'agreement among raft nodes before linearized reading' (duration: 210.644218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:54:55.274282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.073085ms","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-05-11T00:54:55.274352Z","caller":"traceutil/trace.go:171","msg":"trace[561394981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2375; }","duration":"211.214091ms","start":"2026-05-11T00:54:55.063125Z","end":"2026-05-11T00:54:55.274339Z","steps":["trace[561394981] 'agreement among raft nodes before linearized reading' (duration: 210.865767ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:55:39.03688Z","caller":"traceutil/trace.go:171","msg":"trace[1978668621] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"107.951931ms","start":"2026-05-11T00:55:38.928906Z","end":"2026-05-11T00:55:39.036858Z","steps":["trace[1978668621] 'process raft request' (duration: 107.799494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:58:30.646775Z","caller":"traceutil/trace.go:171","msg":"trace[1100058927] transaction","detail":"{read_only:false; response_revision:3730; number_of_response:1; }","duration":"104.556774ms","start":"2026-05-11T00:58:30.542184Z","end":"2026-05-11T00:58:30.646741Z","steps":["trace[1100058927] 'process raft request' (duration: 55.15809ms)","trace[1100058927] 'compare' (duration: 49.096469ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:58:56.189812Z","caller":"traceutil/trace.go:171","msg":"trace[74282810] transaction","detail":"{read_only:false; response_revision:3887; number_of_response:1; }","duration":"103.188048ms","start":"2026-05-11T00:58:56.086586Z","end":"2026-05-11T00:58:56.189774Z","steps":["trace[74282810] 'process raft request' (duration: 102.900272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.02931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552358902,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T00:59:14.19846Z","caller":"traceutil/trace.go:171","msg":"trace[757992995] linearizableReadLoop","detail":"{readStateIndex:4166; appliedIndex:4163; }","duration":"805.074209ms","start":"2026-05-11T00:59:13.393355Z","end":"2026-05-11T00:59:14.19843Z","steps":["trace[757992995] 'read index received' (duration: 804.550616ms)","trace[757992995] 'applied index is now lower than readState.Index' (duration: 522.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:14.198954Z","caller":"traceutil/trace.go:171","msg":"trace[190508533] transaction","detail":"{read_only:false; response_revision:4005; number_of_response:1; }","duration":"1.005246603s","start":"2026-05-11T00:59:13.193697Z","end":"2026-05-11T00:59:14.198944Z","steps":["trace[190508533] 'process raft request' (duration: 1.004337471s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.199047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.193675Z","time spent":"1.005309085s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:14.199313Z","caller":"traceutil/trace.go:171","msg":"trace[732779121] transaction","detail":"{read_only:false; response_revision:4006; number_of_response:1; }","duration":"955.533913ms","start":"2026-05-11T00:59:13.243771Z","end":"2026-05-11T00:59:14.199305Z","steps":["trace[732779121] 'process raft request' (duration: 954.471497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.199359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.243757Z","time spent":"955.579164ms","remote":"127.0.0.1:41968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3985,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:14.199481Z","caller":"traceutil/trace.go:171","msg":"trace[1228854321] transaction","detail":"{read_only:false; response_revision:4007; number_of_response:1; }","duration":"170.239221ms","start":"2026-05-11T00:59:14.029232Z","end":"2026-05-11T00:59:14.199471Z","steps":["trace[1228854321] 'process raft request' (duration: 169.129504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.199594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.237058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:14.199618Z","caller":"traceutil/trace.go:171","msg":"trace[1111861395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4007; }","duration":"806.31759ms","start":"2026-05-11T00:59:13.393294Z","end":"2026-05-11T00:59:14.199611Z","steps":["trace[1111861395] 'agreement among raft nodes before linearized reading' (duration: 806.273219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.199637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.393272Z","time spent":"806.36036ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:14.199806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.608901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-11T00:59:14.199832Z","caller":"traceutil/trace.go:171","msg":"trace[1145126930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4007; }","duration":"588.687824ms","start":"2026-05-11T00:59:13.611137Z","end":"2026-05-11T00:59:14.199825Z","steps":["trace[1145126930] 'agreement among raft nodes before linearized reading' (duration: 588.592622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:14.199869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:13.611113Z","time spent":"588.731434ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:14.200101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.745763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:14.20015Z","caller":"traceutil/trace.go:171","msg":"trace[1219575426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4007; }","duration":"170.851656ms","start":"2026-05-11T00:59:14.029292Z","end":"2026-05-11T00:59:14.200144Z","steps":["trace[1219575426] 'agreement among raft nodes before linearized reading' (duration: 170.789594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.291363Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.541086551s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T00:59:17.887641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.421632Z","time spent":"3.466000359s","remote":"127.0.0.1:60264","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-11T00:59:17.889404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.233091495s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:59:17.891647Z","caller":"traceutil/trace.go:171","msg":"trace[59967619] transaction","detail":"{read_only:false; response_revision:4051; number_of_response:1; }","duration":"3.54438129s","start":"2026-05-11T00:59:14.347223Z","end":"2026-05-11T00:59:17.891604Z","steps":["trace[59967619] 'process raft request' (duration: 3.542913534s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.891698Z","caller":"traceutil/trace.go:171","msg":"trace[1480621075] transaction","detail":"{read_only:false; response_revision:4059; number_of_response:1; }","duration":"3.544087063s","start":"2026-05-11T00:59:14.347576Z","end":"2026-05-11T00:59:17.891664Z","steps":["trace[1480621075] 'process raft request' (duration: 3.542875492s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.891801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.34757Z","time spent":"3.544184975s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10973,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.891822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347218Z","time spent":"3.544525384s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9957,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.891861Z","caller":"traceutil/trace.go:171","msg":"trace[294022331] transaction","detail":"{read_only:false; response_revision:4060; number_of_response:1; }","duration":"3.544226196s","start":"2026-05-11T00:59:14.347616Z","end":"2026-05-11T00:59:17.891842Z","steps":["trace[294022331] 'process raft request' (duration: 3.542867902s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.891927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347611Z","time spent":"3.544288887s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18334,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.891961Z","caller":"traceutil/trace.go:171","msg":"trace[365672580] transaction","detail":"{read_only:false; response_revision:4038; number_of_response:1; }","duration":"3.545334903s","start":"2026-05-11T00:59:14.346614Z","end":"2026-05-11T00:59:17.891949Z","steps":["trace[365672580] 'process raft request' (duration: 309.614981ms)","trace[365672580] 'compare' (duration: 3.231303251s)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:17.892015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346605Z","time spent":"3.545387885s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7682,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892049Z","caller":"traceutil/trace.go:171","msg":"trace[863906211] transaction","detail":"{read_only:false; response_revision:4053; number_of_response:1; }","duration":"3.544716368s","start":"2026-05-11T00:59:14.347324Z","end":"2026-05-11T00:59:17.892041Z","steps":["trace[863906211] 'process raft request' (duration: 3.542897473s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347318Z","time spent":"3.544752829s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":27242,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892137Z","caller":"traceutil/trace.go:171","msg":"trace[1226855882] transaction","detail":"{read_only:false; response_revision:4061; number_of_response:1; }","duration":"3.54439486s","start":"2026-05-11T00:59:14.347731Z","end":"2026-05-11T00:59:17.892126Z","steps":["trace[1226855882] 'process raft request' (duration: 3.54278053s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.892168Z","caller":"traceutil/trace.go:171","msg":"trace[91193855] transaction","detail":"{read_only:false; response_revision:4039; number_of_response:1; }","duration":"3.545457446s","start":"2026-05-11T00:59:14.346701Z","end":"2026-05-11T00:59:17.892159Z","steps":["trace[91193855] 'process raft request' (duration: 3.5428553s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347725Z","time spent":"3.544448201s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9595,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.892214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346696Z","time spent":"3.545498927s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14602,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892231Z","caller":"traceutil/trace.go:171","msg":"trace[1939584048] transaction","detail":"{read_only:false; response_revision:4054; number_of_response:1; }","duration":"3.544856071s","start":"2026-05-11T00:59:14.347366Z","end":"2026-05-11T00:59:17.892222Z","steps":["trace[1939584048] 'process raft request' (duration: 3.542890422s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347361Z","time spent":"3.544896172s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892289Z","caller":"traceutil/trace.go:171","msg":"trace[1075298987] transaction","detail":"{read_only:false; response_revision:4040; number_of_response:1; }","duration":"3.545528758s","start":"2026-05-11T00:59:14.346751Z","end":"2026-05-11T00:59:17.892279Z","steps":["trace[1075298987] 'process raft request' (duration: 3.542898521s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346742Z","time spent":"3.545569699s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24609,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892368Z","caller":"traceutil/trace.go:171","msg":"trace[1447784059] transaction","detail":"{read_only:false; response_revision:4056; number_of_response:1; }","duration":"3.544905493s","start":"2026-05-11T00:59:14.347456Z","end":"2026-05-11T00:59:17.892362Z","steps":["trace[1447784059] 'process raft request' (duration: 3.542902193s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.892397Z","caller":"traceutil/trace.go:171","msg":"trace[1141908370] linearizableReadLoop","detail":"{readStateIndex:4220; appliedIndex:4196; }","duration":"3.420610961s","start":"2026-05-11T00:59:14.471771Z","end":"2026-05-11T00:59:17.892382Z","steps":["trace[1141908370] 'read index received' (duration: 184.385707ms)","trace[1141908370] 'applied index is now lower than readState.Index' (duration: 3.236223414s)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:17.892434Z","caller":"traceutil/trace.go:171","msg":"trace[1713937590] transaction","detail":"{read_only:false; response_revision:4057; number_of_response:1; }","duration":"3.544931304s","start":"2026-05-11T00:59:14.347495Z","end":"2026-05-11T00:59:17.892427Z","steps":["trace[1713937590] 'process raft request' (duration: 3.542894363s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347491Z","time spent":"3.544960404s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892476Z","caller":"traceutil/trace.go:171","msg":"trace[977377369] transaction","detail":"{read_only:false; response_revision:4041; number_of_response:1; }","duration":"3.545666391s","start":"2026-05-11T00:59:14.346795Z","end":"2026-05-11T00:59:17.892461Z","steps":["trace[977377369] 'process raft request' (duration: 3.542899832s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.892537Z","caller":"traceutil/trace.go:171","msg":"trace[1818520880] transaction","detail":"{read_only:false; response_revision:4058; number_of_response:1; }","duration":"3.544995195s","start":"2026-05-11T00:59:14.347534Z","end":"2026-05-11T00:59:17.892529Z","steps":["trace[1818520880] 'process raft request' (duration: 3.542888123s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.892567Z","caller":"traceutil/trace.go:171","msg":"trace[13437489] transaction","detail":"{read_only:false; response_revision:4042; number_of_response:1; }","duration":"3.545720073s","start":"2026-05-11T00:59:14.346836Z","end":"2026-05-11T00:59:17.892556Z","steps":["trace[13437489] 'process raft request' (duration: 3.542924862s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346831Z","time spent":"3.545771664s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15425,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.892406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347451Z","time spent":"3.544939434s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9693,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892725Z","caller":"traceutil/trace.go:171","msg":"trace[527424312] transaction","detail":"{read_only:false; response_revision:4043; number_of_response:1; }","duration":"3.545830776s","start":"2026-05-11T00:59:14.346884Z","end":"2026-05-11T00:59:17.892715Z","steps":["trace[527424312] 'process raft request' (duration: 3.542919362s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.892775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346879Z","time spent":"3.545876157s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19952,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.892574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347529Z","time spent":"3.545029566s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13300,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892854Z","caller":"traceutil/trace.go:171","msg":"trace[1270538434] transaction","detail":"{read_only:false; response_revision:4044; number_of_response:1; }","duration":"3.545916139s","start":"2026-05-11T00:59:14.346928Z","end":"2026-05-11T00:59:17.892844Z","steps":["trace[1270538434] 'process raft request' (duration: 3.542908332s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.8929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346923Z","time spent":"3.54595799s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17441,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892999Z","caller":"traceutil/trace.go:171","msg":"trace[1688006405] transaction","detail":"{read_only:false; response_revision:4045; number_of_response:1; }","duration":"3.546019132s","start":"2026-05-11T00:59:14.346971Z","end":"2026-05-11T00:59:17.89299Z","steps":["trace[1688006405] 'process raft request' (duration: 3.542900182s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.893013Z","caller":"traceutil/trace.go:171","msg":"trace[750798082] transaction","detail":"{read_only:false; response_revision:4050; number_of_response:1; }","duration":"3.545820837s","start":"2026-05-11T00:59:14.347179Z","end":"2026-05-11T00:59:17.893Z","steps":["trace[750798082] 'process raft request' (duration: 3.542923294s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.89305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.346965Z","time spent":"3.546062023s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8497,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.893095Z","caller":"traceutil/trace.go:171","msg":"trace[1822725352] transaction","detail":"{read_only:false; response_revision:4046; number_of_response:1; }","duration":"3.546078153s","start":"2026-05-11T00:59:14.347007Z","end":"2026-05-11T00:59:17.893086Z","steps":["trace[1822725352] 'process raft request' (duration: 3.542889262s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347003Z","time spent":"3.546120844s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9407,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892019Z","caller":"traceutil/trace.go:171","msg":"trace[48245195] transaction","detail":"{read_only:false; response_revision:4052; number_of_response:1; }","duration":"3.544728098s","start":"2026-05-11T00:59:14.347279Z","end":"2026-05-11T00:59:17.892007Z","steps":["trace[48245195] 'process raft request' (duration: 3.542888242s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347275Z","time spent":"3.545914569s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":27451,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.893251Z","caller":"traceutil/trace.go:171","msg":"trace[1811538766] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"3.546150745s","start":"2026-05-11T00:59:14.34709Z","end":"2026-05-11T00:59:17.893241Z","steps":["trace[1811538766] 'process raft request' (duration: 3.542875641s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347085Z","time spent":"3.546189606s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24230,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.892345Z","caller":"traceutil/trace.go:171","msg":"trace[1763454604] transaction","detail":"{read_only:false; response_revision:4055; number_of_response:1; }","duration":"3.544926663s","start":"2026-05-11T00:59:14.34741Z","end":"2026-05-11T00:59:17.892336Z","steps":["trace[1763454604] 'process raft request' (duration: 3.542879322s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347405Z","time spent":"3.545918289s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10660,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.893362Z","caller":"traceutil/trace.go:171","msg":"trace[881634554] transaction","detail":"{read_only:false; response_revision:4047; number_of_response:1; }","duration":"3.546308649s","start":"2026-05-11T00:59:14.347046Z","end":"2026-05-11T00:59:17.893354Z","steps":["trace[881634554] 'process raft request' (duration: 3.542887681s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347041Z","time spent":"3.54634737s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10140,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.892533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.34679Z","time spent":"3.545715163s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":27722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.89343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.360440565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T00:59:17.893063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347174Z","time spent":"3.545863338s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16102,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T00:59:17.893299Z","caller":"traceutil/trace.go:171","msg":"trace[1984617715] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"3.546151075s","start":"2026-05-11T00:59:14.347137Z","end":"2026-05-11T00:59:17.893288Z","steps":["trace[1984617715] 'process raft request' (duration: 3.542927594s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.893557Z","caller":"traceutil/trace.go:171","msg":"trace[221605246] transaction","detail":"{read_only:false; response_revision:4062; number_of_response:1; }","duration":"3.233205349s","start":"2026-05-11T00:59:14.660342Z","end":"2026-05-11T00:59:17.893547Z","steps":["trace[221605246] 'process raft request' (duration: 3.230206654s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.347132Z","time spent":"3.546412701s","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25992,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T00:59:17.893632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.660267Z","time spent":"3.233322613s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:17.893475Z","caller":"traceutil/trace.go:171","msg":"trace[1572212327] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4064; }","duration":"3.360520777s","start":"2026-05-11T00:59:14.532943Z","end":"2026-05-11T00:59:17.893464Z","steps":["trace[1572212327] 'agreement among raft nodes before linearized reading' (duration: 3.360434765s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.53293Z","time spent":"3.360759183s","remote":"127.0.0.1:42184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":3,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-11T00:59:17.893811Z","caller":"traceutil/trace.go:171","msg":"trace[368009798] transaction","detail":"{read_only:false; response_revision:4063; number_of_response:1; }","duration":"1.686760668s","start":"2026-05-11T00:59:16.207039Z","end":"2026-05-11T00:59:17.8938Z","steps":["trace[368009798] 'process raft request' (duration: 1.683563427s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.893877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.207026Z","time spent":"1.686818489s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:17.894005Z","caller":"traceutil/trace.go:171","msg":"trace[499676925] transaction","detail":"{read_only:false; response_revision:4064; number_of_response:1; }","duration":"1.634525337s","start":"2026-05-11T00:59:16.25947Z","end":"2026-05-11T00:59:17.893995Z","steps":["trace[499676925] 'process raft request' (duration: 1.631163032s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624406792s","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-05-11T00:59:17.894051Z","caller":"traceutil/trace.go:171","msg":"trace[241071254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4064; }","duration":"1.624472954s","start":"2026-05-11T00:59:16.269564Z","end":"2026-05-11T00:59:17.894037Z","steps":["trace[241071254] 'agreement among raft nodes before linearized reading' (duration: 1.62430786s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.269554Z","time spent":"1.624528895s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:17.894154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.422384466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T00:59:17.89418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.032929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-11T00:59:17.894209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681558617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-11T00:59:17.894189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.086477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:17.894254Z","caller":"traceutil/trace.go:171","msg":"trace[980847558] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4064; }","duration":"977.187939ms","start":"2026-05-11T00:59:16.917059Z","end":"2026-05-11T00:59:17.894247Z","steps":["trace[980847558] 'agreement among raft nodes before linearized reading' (duration: 977.100727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333247185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-11T00:59:17.894291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.917038Z","time spent":"977.242981ms","remote":"127.0.0.1:57682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.894048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.983093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T00:59:17.89436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967582324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:17.894365Z","caller":"traceutil/trace.go:171","msg":"trace[1214103210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4064; }","duration":"597.304351ms","start":"2026-05-11T00:59:17.297051Z","end":"2026-05-11T00:59:17.894355Z","steps":["trace[1214103210] 'agreement among raft nodes before linearized reading' (duration: 596.952002ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.894386Z","caller":"traceutil/trace.go:171","msg":"trace[1954779738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4064; }","duration":"1.967678686s","start":"2026-05-11T00:59:15.9267Z","end":"2026-05-11T00:59:17.894379Z","steps":["trace[1954779738] 'agreement among raft nodes before linearized reading' (duration: 1.967642935s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.315927238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-11T00:59:17.894398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:17.296998Z","time spent":"597.388732ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-11T00:59:17.894414Z","caller":"traceutil/trace.go:171","msg":"trace[627877823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4064; }","duration":"3.31598218s","start":"2026-05-11T00:59:14.578423Z","end":"2026-05-11T00:59:17.894405Z","steps":["trace[627877823] 'agreement among raft nodes before linearized reading' (duration: 3.315853257s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.89444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.204921238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:59:17.894446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.578412Z","time spent":"3.316026181s","remote":"127.0.0.1:41990","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-05-11T00:59:17.894456Z","caller":"traceutil/trace.go:171","msg":"trace[2136286065] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4064; }","duration":"2.204978049s","start":"2026-05-11T00:59:15.689474Z","end":"2026-05-11T00:59:17.894452Z","steps":["trace[2136286065] 'agreement among raft nodes before linearized reading' (duration: 2.204946818s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.25945Z","time spent":"1.634581638s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:17.894475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:15.689452Z","time spent":"2.20501887s","remote":"127.0.0.1:41976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-11T00:59:17.894255Z","caller":"traceutil/trace.go:171","msg":"trace[1852839332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4064; }","duration":"1.681649289s","start":"2026-05-11T00:59:16.212595Z","end":"2026-05-11T00:59:17.894244Z","steps":["trace[1852839332] 'agreement among raft nodes before linearized reading' (duration: 1.681499445s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.894224Z","caller":"traceutil/trace.go:171","msg":"trace[1972661783] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4064; }","duration":"596.125071ms","start":"2026-05-11T00:59:17.298089Z","end":"2026-05-11T00:59:17.894214Z","steps":["trace[1972661783] 'agreement among raft nodes before linearized reading' (duration: 595.978267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.212564Z","time spent":"1.681993918s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:17.894601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.109633327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:59:17.894327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847825305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:17.894628Z","caller":"traceutil/trace.go:171","msg":"trace[1363198494] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4064; }","duration":"3.109687328s","start":"2026-05-11T00:59:14.784935Z","end":"2026-05-11T00:59:17.894622Z","steps":["trace[1363198494] 'agreement among raft nodes before linearized reading' (duration: 3.109646987s)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:17.894325Z","caller":"traceutil/trace.go:171","msg":"trace[1083178395] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4064; }","duration":"1.333319877s","start":"2026-05-11T00:59:16.560969Z","end":"2026-05-11T00:59:17.894289Z","steps":["trace[1083178395] 'agreement among raft nodes before linearized reading' (duration: 1.333235565s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:15.926665Z","time spent":"1.967738808s","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:59:17.894688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.560949Z","time spent":"1.333722418s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-11T00:59:17.894261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.233530927s","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-05-11T00:59:17.894641Z","caller":"traceutil/trace.go:171","msg":"trace[658921472] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:4064; }","duration":"1.848199724s","start":"2026-05-11T00:59:16.046429Z","end":"2026-05-11T00:59:17.894629Z","steps":["trace[658921472] 'agreement among raft nodes before linearized reading' (duration: 1.847844696s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.89479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:16.046409Z","time spent":"1.848368568s","remote":"127.0.0.1:57706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.894601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:17.29806Z","time spent":"596.531562ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-11T00:59:17.894744Z","caller":"traceutil/trace.go:171","msg":"trace[619982661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4064; }","duration":"3.234055391s","start":"2026-05-11T00:59:14.660674Z","end":"2026-05-11T00:59:17.894729Z","steps":["trace[619982661] 'agreement among raft nodes before linearized reading' (duration: 3.233533348s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.894654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.784922Z","time spent":"3.109725629s","remote":"127.0.0.1:57700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.894949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.660645Z","time spent":"3.234293266s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T00:59:17.894186Z","caller":"traceutil/trace.go:171","msg":"trace[1788353896] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4064; }","duration":"3.422414977s","start":"2026-05-11T00:59:14.47176Z","end":"2026-05-11T00:59:17.894175Z","steps":["trace[1788353896] 'agreement among raft nodes before linearized reading' (duration: 3.420869238s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.895045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.471702Z","time spent":"3.423331911s","remote":"127.0.0.1:52944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:17.894802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.233844366s","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-05-11T00:59:17.895249Z","caller":"traceutil/trace.go:171","msg":"trace[675680385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4064; }","duration":"3.234307107s","start":"2026-05-11T00:59:14.660933Z","end":"2026-05-11T00:59:17.89524Z","steps":["trace[675680385] 'agreement among raft nodes before linearized reading' (duration: 3.233820825s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:17.895278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:14.660927Z","time spent":"3.234344018s","remote":"127.0.0.1:41990","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-05-11T00:59:27.159163Z","caller":"traceutil/trace.go:171","msg":"trace[791859965] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"117.171566ms","start":"2026-05-11T00:59:27.041959Z","end":"2026-05-11T00:59:27.15913Z","steps":["trace[791859965] 'process raft request' (duration: 116.988282ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:27.159734Z","caller":"traceutil/trace.go:171","msg":"trace[1005434821] linearizableReadLoop","detail":"{readStateIndex:4729; appliedIndex:4729; }","duration":"100.849665ms","start":"2026-05-11T00:59:27.058838Z","end":"2026-05-11T00:59:27.159687Z","steps":["trace[1005434821] 'read index received' (duration: 100.837465ms)","trace[1005434821] 'applied index is now lower than readState.Index' (duration: 9.17µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:27.160363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.124123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:27.160429Z","caller":"traceutil/trace.go:171","msg":"trace[63421877] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4558; }","duration":"101.613096ms","start":"2026-05-11T00:59:27.058796Z","end":"2026-05-11T00:59:27.160409Z","steps":["trace[63421877] 'agreement among raft nodes before linearized reading' (duration: 101.054741ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:33.971144Z","caller":"traceutil/trace.go:171","msg":"trace[1059358943] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"105.095887ms","start":"2026-05-11T00:59:33.866008Z","end":"2026-05-11T00:59:33.971104Z","steps":["trace[1059358943] 'process raft request' (duration: 51.250639ms)","trace[1059358943] 'compare' (duration: 53.612091ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:34.087067Z","caller":"traceutil/trace.go:171","msg":"trace[132276407] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"107.387967ms","start":"2026-05-11T00:59:33.979633Z","end":"2026-05-11T00:59:34.087021Z","steps":["trace[132276407] 'process raft request' (duration: 96.454959ms)","trace[132276407] 'compare' (duration: 10.684571ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:34.087477Z","caller":"traceutil/trace.go:171","msg":"trace[1333989100] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"107.734406ms","start":"2026-05-11T00:59:33.979711Z","end":"2026-05-11T00:59:34.087446Z","steps":["trace[1333989100] 'process raft request' (duration: 107.262153ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:34.087553Z","caller":"traceutil/trace.go:171","msg":"trace[2008659677] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"107.582392ms","start":"2026-05-11T00:59:33.979949Z","end":"2026-05-11T00:59:34.087531Z","steps":["trace[2008659677] 'process raft request' (duration: 107.426678ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:34.088206Z","caller":"traceutil/trace.go:171","msg":"trace[1354856988] linearizableReadLoop","detail":"{readStateIndex:4794; appliedIndex:4790; }","duration":"108.049636ms","start":"2026-05-11T00:59:33.980141Z","end":"2026-05-11T00:59:34.088191Z","steps":["trace[1354856988] 'read index received' (duration: 62.888208ms)","trace[1354856988] 'applied index is now lower than readState.Index' (duration: 45.159488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:34.088468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.300173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-11T00:59:34.088515Z","caller":"traceutil/trace.go:171","msg":"trace[713520025] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4622; }","duration":"108.404505ms","start":"2026-05-11T00:59:33.980096Z","end":"2026-05-11T00:59:34.088501Z","steps":["trace[713520025] 'agreement among raft nodes before linearized reading' (duration: 108.21795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:34.088624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.466377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-05-11T00:59:34.088702Z","caller":"traceutil/trace.go:171","msg":"trace[1596916541] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4622; }","duration":"108.544969ms","start":"2026-05-11T00:59:33.980107Z","end":"2026-05-11T00:59:34.088652Z","steps":["trace[1596916541] 'agreement among raft nodes before linearized reading' (duration: 108.338324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:34.340957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.228086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:59:34.341056Z","caller":"traceutil/trace.go:171","msg":"trace[149766293] linearizableReadLoop","detail":"{readStateIndex:4803; appliedIndex:4802; }","duration":"195.695932ms","start":"2026-05-11T00:59:34.145343Z","end":"2026-05-11T00:59:34.341039Z","steps":["trace[149766293] 'read index received' (duration: 49.080636ms)","trace[149766293] 'applied index is now lower than readState.Index' (duration: 146.614196ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:34.341596Z","caller":"traceutil/trace.go:171","msg":"trace[722542798] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"196.711489ms","start":"2026-05-11T00:59:34.144844Z","end":"2026-05-11T00:59:34.341555Z","steps":["trace[722542798] 'process raft request' (duration: 49.788134ms)","trace[722542798] 'compare' (duration: 146.097963ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:34.341931Z","caller":"traceutil/trace.go:171","msg":"trace[1966166302] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"195.246168ms","start":"2026-05-11T00:59:34.146659Z","end":"2026-05-11T00:59:34.341905Z","steps":["trace[1966166302] 'process raft request' (duration: 194.896939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:34.344072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.609451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16554"} {"level":"info","ts":"2026-05-11T00:59:34.344189Z","caller":"traceutil/trace.go:171","msg":"trace[1198653853] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"197.393947ms","start":"2026-05-11T00:59:34.146766Z","end":"2026-05-11T00:59:34.34416Z","steps":["trace[1198653853] 'process raft request' (duration: 194.9331ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:34.344237Z","caller":"traceutil/trace.go:171","msg":"trace[31476310] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4631; }","duration":"198.910729ms","start":"2026-05-11T00:59:34.145304Z","end":"2026-05-11T00:59:34.344215Z","steps":["trace[31476310] 'agreement among raft nodes before linearized reading' (duration: 195.782684ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:34.344957Z","caller":"traceutil/trace.go:171","msg":"trace[1499501243] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"197.174641ms","start":"2026-05-11T00:59:34.147769Z","end":"2026-05-11T00:59:34.344944Z","steps":["trace[1499501243] 'process raft request' (duration: 196.059851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:40.863329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.779985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T00:59:40.863589Z","caller":"traceutil/trace.go:171","msg":"trace[105553420] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4950; }","duration":"345.249414ms","start":"2026-05-11T00:59:40.518318Z","end":"2026-05-11T00:59:40.863567Z","steps":["trace[105553420] 'read index received' (duration: 343.541487ms)","trace[105553420] 'applied index is now lower than readState.Index' (duration: 1.706567ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:40.863684Z","caller":"traceutil/trace.go:171","msg":"trace[479168075] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"355.425488ms","start":"2026-05-11T00:59:40.508249Z","end":"2026-05-11T00:59:40.863675Z","steps":["trace[479168075] 'process raft request' (duration: 355.212172ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:40.863707Z","caller":"traceutil/trace.go:171","msg":"trace[266469556] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"482.829491ms","start":"2026-05-11T00:59:40.38085Z","end":"2026-05-11T00:59:40.863679Z","steps":["trace[266469556] 'process raft request' (duration: 75.609603ms)","trace[266469556] 'compare' (duration: 406.603021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T00:59:40.863741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:40.508211Z","time spent":"355.495479ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:40.863838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:40.380837Z","time spent":"482.929653ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7599,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:40.864022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.995733ms","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-05-11T00:59:40.864139Z","caller":"traceutil/trace.go:171","msg":"trace[1878924228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4775; }","duration":"319.174908ms","start":"2026-05-11T00:59:40.544934Z","end":"2026-05-11T00:59:40.864109Z","steps":["trace[1878924228] 'agreement among raft nodes before linearized reading' (duration: 318.944351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:40.864161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.867431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-11T00:59:40.864207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:40.544911Z","time spent":"319.283552ms","remote":"127.0.0.1:57784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-11T00:59:40.864223Z","caller":"traceutil/trace.go:171","msg":"trace[1425615002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4775; }","duration":"345.955014ms","start":"2026-05-11T00:59:40.518247Z","end":"2026-05-11T00:59:40.864202Z","steps":["trace[1425615002] 'agreement among raft nodes before linearized reading' (duration: 345.815399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:40.864151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.387619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T00:59:40.864258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:40.518222Z","time spent":"346.029546ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T00:59:40.864277Z","caller":"traceutil/trace.go:171","msg":"trace[631148434] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4775; }","duration":"312.540345ms","start":"2026-05-11T00:59:40.551722Z","end":"2026-05-11T00:59:40.864263Z","steps":["trace[631148434] 'agreement among raft nodes before linearized reading' (duration: 312.38645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:40.864378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:40.551685Z","time spent":"312.618287ms","remote":"127.0.0.1:57758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T00:59:55.517628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552360696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:56.018701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552360696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:56.519863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552360696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:56.670187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.741367288s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T00:59:56.670827Z","caller":"traceutil/trace.go:171","msg":"trace[1298477144] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"1.74210186s","start":"2026-05-11T00:59:54.928695Z","end":"2026-05-11T00:59:56.670797Z","steps":["trace[1298477144] 'process raft request' (duration: 1.741715259s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.67104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:54.928679Z","time spent":"1.742279625s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:56.674107Z","caller":"traceutil/trace.go:171","msg":"trace[1379456398] linearizableReadLoop","detail":"{readStateIndex:5093; appliedIndex:5089; }","duration":"1.657027676s","start":"2026-05-11T00:59:55.017063Z","end":"2026-05-11T00:59:56.67409Z","steps":["trace[1379456398] 'read index received' (duration: 1.653291397s)","trace[1379456398] 'applied index is now lower than readState.Index' (duration: 3.735259ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T00:59:56.674187Z","caller":"traceutil/trace.go:171","msg":"trace[2009439477] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"499.921973ms","start":"2026-05-11T00:59:56.174258Z","end":"2026-05-11T00:59:56.67418Z","steps":["trace[2009439477] 'process raft request' (duration: 499.758398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.674245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.174231Z","time spent":"499.977414ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:56.674292Z","caller":"traceutil/trace.go:171","msg":"trace[1436377523] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"1.616660355s","start":"2026-05-11T00:59:55.057598Z","end":"2026-05-11T00:59:56.674258Z","steps":["trace[1436377523] 'process raft request' (duration: 1.616331616s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.674398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.93028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T00:59:56.674432Z","caller":"traceutil/trace.go:171","msg":"trace[448499678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4913; }","duration":"740.989192ms","start":"2026-05-11T00:59:55.933435Z","end":"2026-05-11T00:59:56.674424Z","steps":["trace[448499678] 'agreement among raft nodes before linearized reading' (duration: 740.93114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.674464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:55.933422Z","time spent":"741.031533ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T00:59:56.674561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657499019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-11T00:59:56.674483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:55.057577Z","time spent":"1.61682782s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11904,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T00:59:56.674445Z","caller":"traceutil/trace.go:171","msg":"trace[117148775] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"497.497861ms","start":"2026-05-11T00:59:56.17689Z","end":"2026-05-11T00:59:56.674388Z","steps":["trace[117148775] 'process raft request' (duration: 497.167971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.674761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.637689ms","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-05-11T00:59:56.674768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.443463ms","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-05-11T00:59:56.674801Z","caller":"traceutil/trace.go:171","msg":"trace[1438127235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4913; }","duration":"431.68313ms","start":"2026-05-11T00:59:56.243108Z","end":"2026-05-11T00:59:56.674791Z","steps":["trace[1438127235] 'agreement among raft nodes before linearized reading' (duration: 431.586017ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T00:59:56.674825Z","caller":"traceutil/trace.go:171","msg":"trace[1031390758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4913; }","duration":"493.542996ms","start":"2026-05-11T00:59:56.181266Z","end":"2026-05-11T00:59:56.674809Z","steps":["trace[1031390758] 'agreement among raft nodes before linearized reading' (duration: 493.383901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.674848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.243104Z","time spent":"431.735882ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:56.674855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.176868Z","time spent":"497.887483ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T00:59:56.674661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.651869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-11T00:59:56.674612Z","caller":"traceutil/trace.go:171","msg":"trace[1563503742] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4913; }","duration":"1.657586752s","start":"2026-05-11T00:59:55.017012Z","end":"2026-05-11T00:59:56.674599Z","steps":["trace[1563503742] 'agreement among raft nodes before linearized reading' (duration: 1.657265993s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.675231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:55.016994Z","time spent":"1.658222721s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T00:59:56.675274Z","caller":"traceutil/trace.go:171","msg":"trace[1889992572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4913; }","duration":"432.04403ms","start":"2026-05-11T00:59:56.242969Z","end":"2026-05-11T00:59:56.675013Z","steps":["trace[1889992572] 'agreement among raft nodes before linearized reading' (duration: 431.410702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.674717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.965433ms","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-05-11T00:59:56.675368Z","caller":"traceutil/trace.go:171","msg":"trace[804691539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4913; }","duration":"440.645654ms","start":"2026-05-11T00:59:56.23471Z","end":"2026-05-11T00:59:56.675356Z","steps":["trace[804691539] 'agreement among raft nodes before linearized reading' (duration: 439.912852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.675404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.234695Z","time spent":"440.700726ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T00:59:56.674873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.181235Z","time spent":"493.629439ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T00:59:56.674928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.224065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T00:59:56.675428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.242935Z","time spent":"432.371971ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T00:59:56.675515Z","caller":"traceutil/trace.go:171","msg":"trace[1481889679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4913; }","duration":"447.834554ms","start":"2026-05-11T00:59:56.227667Z","end":"2026-05-11T00:59:56.675501Z","steps":["trace[1481889679] 'agreement among raft nodes before linearized reading' (duration: 447.102812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:56.675615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:56.227653Z","time spent":"447.922767ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T00:59:57.907305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552360703,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:58.407918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552360703,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:58.908395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699159552360703,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T00:59:59.225394Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.951232785s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T00:59:59.926128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000333004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-11T00:59:59.926219Z","caller":"traceutil/trace.go:171","msg":"trace[1942046121] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000484468s","start":"2026-05-11T00:59:57.925714Z","end":"2026-05-11T00:59:59.926198Z","steps":["trace[1942046121] 'agreement among raft nodes before linearized reading' (duration: 2.000365205s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T00:59:59.926268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.925675Z","time spent":"2.000582641s","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:00:00.679323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453705655s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T01:00:00.681231Z","caller":"traceutil/trace.go:171","msg":"trace[539702412] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"3.012459695s","start":"2026-05-11T00:59:57.668747Z","end":"2026-05-11T01:00:00.681207Z","steps":["trace[539702412] 'process raft request' (duration: 3.010780165s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.681454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.66872Z","time spent":"3.012641871s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:00:00.696791Z","caller":"traceutil/trace.go:171","msg":"trace[1496928] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"2.015742453s","start":"2026-05-11T00:59:58.681026Z","end":"2026-05-11T01:00:00.696769Z","steps":["trace[1496928] 'process raft request' (duration: 2.01563211s)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:00.696775Z","caller":"traceutil/trace.go:171","msg":"trace[476774473] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5099; }","duration":"3.289511606s","start":"2026-05-11T00:59:57.407243Z","end":"2026-05-11T01:00:00.696754Z","steps":["trace[476774473] 'read index received' (duration: 1.818391894s)","trace[476774473] 'applied index is now lower than readState.Index' (duration: 1.471118842s)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:00.696828Z","caller":"traceutil/trace.go:171","msg":"trace[1152660354] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"2.02034495s","start":"2026-05-11T00:59:58.676426Z","end":"2026-05-11T01:00:00.696771Z","steps":["trace[1152660354] 'process raft request' (duration: 2.004762317s)","trace[1152660354] 'compare' (duration: 15.209742ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:00:00.696965Z","caller":"traceutil/trace.go:171","msg":"trace[1682858529] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"2.01530026s","start":"2026-05-11T00:59:58.681608Z","end":"2026-05-11T01:00:00.696908Z","steps":["trace[1682858529] 'process raft request' (duration: 2.015109285s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.696935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.681005Z","time spent":"2.015850048s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:00:00.697081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.091068683s","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-05-11T01:00:00.697159Z","caller":"traceutil/trace.go:171","msg":"trace[1662769127] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4923; }","duration":"3.091187766s","start":"2026-05-11T00:59:57.605962Z","end":"2026-05-11T01:00:00.69715Z","steps":["trace[1662769127] 'agreement among raft nodes before linearized reading' (duration: 3.091083973s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.605913Z","time spent":"3.09128288s","remote":"127.0.0.1:41862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:00:00.697198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.676402Z","time spent":"2.020585397s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:00:00.697232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.289826315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T01:00:00.697217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.68159Z","time spent":"2.015466756s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:00:00.69732Z","caller":"traceutil/trace.go:171","msg":"trace[1683739894] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4923; }","duration":"3.290108583s","start":"2026-05-11T00:59:57.40719Z","end":"2026-05-11T01:00:00.697298Z","steps":["trace[1683739894] 'agreement among raft nodes before linearized reading' (duration: 3.289791804s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.69732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.918492173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-11T01:00:00.697312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007305085s","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-05-11T01:00:00.697397Z","caller":"traceutil/trace.go:171","msg":"trace[1404863093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4923; }","duration":"2.007414698s","start":"2026-05-11T00:59:58.689976Z","end":"2026-05-11T01:00:00.697391Z","steps":["trace[1404863093] 'agreement among raft nodes before linearized reading' (duration: 2.007221192s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00714582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-11T01:00:00.697423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.689969Z","time spent":"2.007449188s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T01:00:00.697439Z","caller":"traceutil/trace.go:171","msg":"trace[1369011233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4923; }","duration":"2.007195392s","start":"2026-05-11T00:59:58.690236Z","end":"2026-05-11T01:00:00.697431Z","steps":["trace[1369011233] 'agreement among raft nodes before linearized reading' (duration: 2.007110329s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.209173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:00:00.697476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010367875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-11T01:00:00.69749Z","caller":"traceutil/trace.go:171","msg":"trace[381582010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4923; }","duration":"770.254844ms","start":"2026-05-11T00:59:59.92723Z","end":"2026-05-11T01:00:00.697485Z","steps":["trace[381582010] 'agreement among raft nodes before linearized reading' (duration: 770.219923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:57.407161Z","time spent":"3.290199006s","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:00:00.697506Z","caller":"traceutil/trace.go:171","msg":"trace[1887039560] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4923; }","duration":"2.010413456s","start":"2026-05-11T00:59:58.687081Z","end":"2026-05-11T01:00:00.697494Z","steps":["trace[1887039560] 'agreement among raft nodes before linearized reading' (duration: 2.010359774s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.008125109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-11T01:00:00.697523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:59.927214Z","time spent":"770.302215ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T01:00:00.697528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.687069Z","time spent":"2.010454317s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T01:00:00.697542Z","caller":"traceutil/trace.go:171","msg":"trace[1368678805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4923; }","duration":"2.008270004s","start":"2026-05-11T00:59:58.689265Z","end":"2026-05-11T01:00:00.697535Z","steps":["trace[1368678805] 'agreement among raft nodes before linearized reading' (duration: 2.008109259s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.689254Z","time spent":"2.008304664s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T01:00:00.697616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.012168368s","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-05-11T01:00:00.697692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007619754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-11T01:00:00.697742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696355813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-05-11T01:00:00.697759Z","caller":"traceutil/trace.go:171","msg":"trace[633199208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4923; }","duration":"2.007708767s","start":"2026-05-11T00:59:58.690032Z","end":"2026-05-11T01:00:00.697741Z","steps":["trace[633199208] 'agreement among raft nodes before linearized reading' (duration: 2.007453659s)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:00:00.697775Z","caller":"traceutil/trace.go:171","msg":"trace[918258462] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4923; }","duration":"1.696412655s","start":"2026-05-11T00:59:59.001354Z","end":"2026-05-11T01:00:00.697767Z","steps":["trace[918258462] 'agreement among raft nodes before linearized reading' (duration: 1.696331262s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.690228Z","time spent":"2.007252903s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T01:00:00.697718Z","caller":"traceutil/trace.go:171","msg":"trace[1131710835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4923; }","duration":"2.012306492s","start":"2026-05-11T00:59:58.685403Z","end":"2026-05-11T01:00:00.69771Z","steps":["trace[1131710835] 'agreement among raft nodes before linearized reading' (duration: 2.012168508s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.685393Z","time spent":"2.012507348s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T01:00:00.697407Z","caller":"traceutil/trace.go:171","msg":"trace[1706403974] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4923; }","duration":"1.918623197s","start":"2026-05-11T00:59:58.778767Z","end":"2026-05-11T01:00:00.697391Z","steps":["trace[1706403974] 'agreement among raft nodes before linearized reading' (duration: 1.918378569s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.69002Z","time spent":"2.007770688s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T01:00:00.697636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335276578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T01:00:00.6981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:58.778756Z","time spent":"1.919332778s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-11T01:00:00.69812Z","caller":"traceutil/trace.go:171","msg":"trace[691700853] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4923; }","duration":"1.335781654s","start":"2026-05-11T00:59:59.362327Z","end":"2026-05-11T01:00:00.698108Z","steps":["trace[691700853] 'agreement among raft nodes before linearized reading' (duration: 1.335284108s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.697672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.824913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-11T01:00:00.698177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:59.362312Z","time spent":"1.335826635s","remote":"127.0.0.1:44906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-05-11T01:00:00.69819Z","caller":"traceutil/trace.go:171","msg":"trace[1998776089] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:4923; }","duration":"923.41156ms","start":"2026-05-11T00:59:59.774763Z","end":"2026-05-11T01:00:00.698174Z","steps":["trace[1998776089] 'agreement among raft nodes before linearized reading' (duration: 922.889274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:00:00.698327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:59.774738Z","time spent":"923.534354ms","remote":"127.0.0.1:60728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-05-11T01:00:00.697804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T00:59:59.001335Z","time spent":"1.696464476s","remote":"127.0.0.1:57664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-11T01:01:19.162088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-05-11T01:01:19.233666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"70.079691ms","hash":3838888858,"current-db-size-bytes":53432320,"current-db-size":"53 MB","current-db-size-in-use-bytes":28819456,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T01:01:19.233732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3838888858,"revision":2892,"compact-revision":-1} {"level":"info","ts":"2026-05-11T01:02:05.333972Z","caller":"traceutil/trace.go:171","msg":"trace[1906962986] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"152.341723ms","start":"2026-05-11T01:02:05.181602Z","end":"2026-05-11T01:02:05.333944Z","steps":["trace[1906962986] 'process raft request' (duration: 152.182247ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:05.342277Z","caller":"traceutil/trace.go:171","msg":"trace[1172240797] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"118.43344ms","start":"2026-05-11T01:02:05.223824Z","end":"2026-05-11T01:02:05.342258Z","steps":["trace[1172240797] 'process raft request' (duration: 118.403739ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:05.342653Z","caller":"traceutil/trace.go:171","msg":"trace[1895840907] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"143.173257ms","start":"2026-05-11T01:02:05.19944Z","end":"2026-05-11T01:02:05.342614Z","steps":["trace[1895840907] 'process raft request' (duration: 142.69824ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:02:11.51598Z","caller":"traceutil/trace.go:171","msg":"trace[169851796] transaction","detail":"{read_only:false; response_revision:5845; number_of_response:1; }","duration":"106.576986ms","start":"2026-05-11T01:02:11.409381Z","end":"2026-05-11T01:02:11.515958Z","steps":["trace[169851796] 'process raft request' (duration: 106.429741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.814792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.137913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T01:02:11.815038Z","caller":"traceutil/trace.go:171","msg":"trace[2114236611] linearizableReadLoop","detail":"{readStateIndex:6058; appliedIndex:6055; }","duration":"346.672212ms","start":"2026-05-11T01:02:11.468347Z","end":"2026-05-11T01:02:11.81502Z","steps":["trace[2114236611] 'read index received' (duration: 47.49485ms)","trace[2114236611] 'applied index is now lower than readState.Index' (duration: 299.176272ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:02:11.815158Z","caller":"traceutil/trace.go:171","msg":"trace[773890370] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"393.201947ms","start":"2026-05-11T01:02:11.421939Z","end":"2026-05-11T01:02:11.815141Z","steps":["trace[773890370] 'process raft request' (duration: 192.607206ms)","trace[773890370] 'compare' (duration: 199.79378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:02:11.815422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.074216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-11T01:02:11.815625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.092021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-11T01:02:11.815712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.572336ms","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-05-11T01:02:11.815723Z","caller":"traceutil/trace.go:171","msg":"trace[97108757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5847; }","duration":"324.213044ms","start":"2026-05-11T01:02:11.491496Z","end":"2026-05-11T01:02:11.815709Z","steps":["trace[97108757] 'agreement among raft nodes before linearized reading' (duration: 324.001927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.815689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.604292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T01:02:11.815863Z","caller":"traceutil/trace.go:171","msg":"trace[1343176352] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5847; }","duration":"323.80741ms","start":"2026-05-11T01:02:11.492033Z","end":"2026-05-11T01:02:11.81584Z","steps":["trace[1343176352] 'agreement among raft nodes before linearized reading' (duration: 323.479528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.815819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.421915Z","time spent":"393.284ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T01:02:11.815794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.491485Z","time spent":"324.296428ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T01:02:11.815755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.323099ms","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-05-11T01:02:11.816082Z","caller":"traceutil/trace.go:171","msg":"trace[1020578383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5847; }","duration":"327.668661ms","start":"2026-05-11T01:02:11.488396Z","end":"2026-05-11T01:02:11.816065Z","steps":["trace[1020578383] 'agreement among raft nodes before linearized reading' (duration: 327.229995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.815578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.691077ms","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-05-11T01:02:11.816192Z","caller":"traceutil/trace.go:171","msg":"trace[800111458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5847; }","duration":"336.345441ms","start":"2026-05-11T01:02:11.479831Z","end":"2026-05-11T01:02:11.816176Z","steps":["trace[800111458] 'agreement among raft nodes before linearized reading' (duration: 335.579243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.816192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.488374Z","time spent":"327.801336ms","remote":"127.0.0.1:41990","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-05-11T01:02:11.816149Z","caller":"traceutil/trace.go:171","msg":"trace[341067203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5847; }","duration":"347.835465ms","start":"2026-05-11T01:02:11.468296Z","end":"2026-05-11T01:02:11.816132Z","steps":["trace[341067203] 'agreement among raft nodes before linearized reading' (duration: 346.969233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.816377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.46827Z","time spent":"348.088103ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T01:02:11.816235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.479818Z","time spent":"336.405303ms","remote":"127.0.0.1:41990","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-05-11T01:02:11.816105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.492024Z","time spent":"323.880582ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T01:02:11.815575Z","caller":"traceutil/trace.go:171","msg":"trace[1091471367] transaction","detail":"{read_only:false; response_revision:5847; number_of_response:1; }","duration":"392.750419ms","start":"2026-05-11T01:02:11.4228Z","end":"2026-05-11T01:02:11.815551Z","steps":["trace[1091471367] 'process raft request' (duration: 392.114685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.816773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.422788Z","time spent":"393.919132ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T01:02:11.815775Z","caller":"traceutil/trace.go:171","msg":"trace[1901607166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5847; }","duration":"328.665689ms","start":"2026-05-11T01:02:11.487092Z","end":"2026-05-11T01:02:11.815757Z","steps":["trace[1901607166] 'agreement among raft nodes before linearized reading' (duration: 328.545935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:02:11.81685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T01:02:11.487074Z","time spent":"329.756639ms","remote":"127.0.0.1:41990","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":"info","ts":"2026-05-11T01:05:16.955765Z","caller":"traceutil/trace.go:171","msg":"trace[1056028360] transaction","detail":"{read_only:false; response_revision:7070; number_of_response:1; }","duration":"152.17452ms","start":"2026-05-11T01:05:16.803561Z","end":"2026-05-11T01:05:16.955735Z","steps":["trace[1056028360] 'process raft request' (duration: 99.616929ms)","trace[1056028360] 'compare' (duration: 52.20583ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T01:05:16.955774Z","caller":"traceutil/trace.go:171","msg":"trace[241684643] transaction","detail":"{read_only:false; response_revision:7071; number_of_response:1; }","duration":"150.994784ms","start":"2026-05-11T01:05:16.804727Z","end":"2026-05-11T01:05:16.955721Z","steps":["trace[241684643] 'process raft request' (duration: 150.878583ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:05:16.955869Z","caller":"traceutil/trace.go:171","msg":"trace[94725688] linearizableReadLoop","detail":"{readStateIndex:7326; appliedIndex:7325; }","duration":"151.227865ms","start":"2026-05-11T01:05:16.804613Z","end":"2026-05-11T01:05:16.955841Z","steps":["trace[94725688] 'read index received' (duration: 98.647854ms)","trace[94725688] 'applied index is now lower than readState.Index' (duration: 52.576671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T01:05:16.956137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.487166ms","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-05-11T01:05:16.956212Z","caller":"traceutil/trace.go:171","msg":"trace[1610213395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7071; }","duration":"151.619567ms","start":"2026-05-11T01:05:16.804581Z","end":"2026-05-11T01:05:16.9562Z","steps":["trace[1610213395] 'agreement among raft nodes before linearized reading' (duration: 151.340926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T01:05:16.956236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.585847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-11T01:05:16.956329Z","caller":"traceutil/trace.go:171","msg":"trace[1203594841] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7071; }","duration":"151.704457ms","start":"2026-05-11T01:05:16.804608Z","end":"2026-05-11T01:05:16.956313Z","steps":["trace[1203594841] 'agreement among raft nodes before linearized reading' (duration: 151.379586ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T01:06:19.169138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5567} {"level":"info","ts":"2026-05-11T01:06:19.247369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5567,"took":"76.873539ms","hash":2339558038,"current-db-size-bytes":53432320,"current-db-size":"53 MB","current-db-size-in-use-bytes":18100224,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-11T01:06:19.24742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2339558038,"revision":5567,"compact-revision":2892}