{"level":"warn","ts":"2026-04-07T01:25:45.726666Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:25:45.726769Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T01:25:45.726841Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:25:45.726857Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-07T01:25:45.726877Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T01:25:45.727501Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-07T01:25:45.727597Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T01:25:45.731595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.697627ms"} {"level":"info","ts":"2026-04-07T01:25:45.737741Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-07T01:25:45.737853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:25:45.737888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-07T01:25:45.737903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:25:45.737922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-07T01:25:45.737972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-07T01:25:45.743888Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:25:45.745676Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:25:45.747532Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T01:25:45.751143Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:25:45.751357Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:25:45.751481Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T01:25:45.751609Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T01:25:45.75163Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T01:25:45.752546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-07T01:25:45.75268Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-07T01:25:45.756432Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T01:25:45.756566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-07T01:25:45.756691Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-07T01:25:45.756775Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:25:45.756842Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:25:45.838187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:25:45.838274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:25:45.838303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-07T01:25:45.838323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:25:45.838335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-07T01:25:45.838349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-07T01:25:45.838362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-07T01:25:45.84019Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:45.841507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:25:45.841565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:25:45.842229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:25:45.842599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:25:45.842691Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:25:45.842951Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:45.84328Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:45.843375Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:45.846218Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:25:45.84626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-04-07T01:25:52.855251Z","caller":"traceutil/trace.go:171","msg":"trace[914204036] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"160.625425ms","start":"2026-04-07T01:25:52.694592Z","end":"2026-04-07T01:25:52.855217Z","steps":["trace[914204036] 'process raft request' (duration: 160.4353ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:56.662285Z","caller":"traceutil/trace.go:171","msg":"trace[596826761] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"118.815415ms","start":"2026-04-07T01:25:56.543447Z","end":"2026-04-07T01:25:56.662262Z","steps":["trace[596826761] 'read index received' (duration: 118.141399ms)","trace[596826761] 'applied index is now lower than readState.Index' (duration: 673.026µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:56.662374Z","caller":"traceutil/trace.go:171","msg":"trace[366285379] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"124.574909ms","start":"2026-04-07T01:25:56.537767Z","end":"2026-04-07T01:25:56.662342Z","steps":["trace[366285379] 'process raft request' (duration: 123.812092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:56.662536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.07412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-07T01:25:56.662636Z","caller":"traceutil/trace.go:171","msg":"trace[1647147837] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:352; }","duration":"119.218494ms","start":"2026-04-07T01:25:56.543406Z","end":"2026-04-07T01:25:56.662624Z","steps":["trace[1647147837] 'agreement among raft nodes before linearized reading' (duration: 119.067631ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:23.97061Z","caller":"traceutil/trace.go:171","msg":"trace[1841651573] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"288.471605ms","start":"2026-04-07T01:26:23.682105Z","end":"2026-04-07T01:26:23.970577Z","steps":["trace[1841651573] 'process raft request' (duration: 288.26752ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:24.02858Z","caller":"traceutil/trace.go:171","msg":"trace[111021973] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"312.928945ms","start":"2026-04-07T01:26:23.715612Z","end":"2026-04-07T01:26:24.028541Z","steps":["trace[111021973] 'process raft request' (duration: 312.282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:24.029387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:23.715581Z","time spent":"313.114309ms","remote":"127.0.0.1:39160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:24.753413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.39323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:26:24.753562Z","caller":"traceutil/trace.go:171","msg":"trace[1085232239] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"719.620241ms","start":"2026-04-07T01:26:24.033919Z","end":"2026-04-07T01:26:24.753539Z","steps":["trace[1085232239] 'process raft request' (duration: 407.76246ms)","trace[1085232239] 'compare' (duration: 311.176866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:26:24.753632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.03389Z","time spent":"719.711223ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:26:24.761786Z","caller":"traceutil/trace.go:171","msg":"trace[868865373] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"727.206918ms","start":"2026-04-07T01:26:24.034519Z","end":"2026-04-07T01:26:24.761726Z","steps":["trace[868865373] 'process raft request' (duration: 726.793269ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:24.761841Z","caller":"traceutil/trace.go:171","msg":"trace[2020521036] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"727.072725ms","start":"2026-04-07T01:26:24.034716Z","end":"2026-04-07T01:26:24.761788Z","steps":["trace[2020521036] 'process raft request' (duration: 726.846039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:24.761878Z","caller":"traceutil/trace.go:171","msg":"trace[554204060] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:641; }","duration":"480.870175ms","start":"2026-04-07T01:26:24.280978Z","end":"2026-04-07T01:26:24.761848Z","steps":["trace[554204060] 'read index received' (duration: 160.742781ms)","trace[554204060] 'applied index is now lower than readState.Index' (duration: 320.126104ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:26:24.761922Z","caller":"traceutil/trace.go:171","msg":"trace[994660185] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"672.560646ms","start":"2026-04-07T01:26:24.089324Z","end":"2026-04-07T01:26:24.761884Z","steps":["trace[994660185] 'process raft request' (duration: 672.30942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:24.762038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.034473Z","time spent":"727.431633ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:24.762086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.034684Z","time spent":"727.27196ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:26:24.762131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.089281Z","time spent":"672.773031ms","remote":"127.0.0.1:39300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:26:24.762206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.206552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nlx9z\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T01:26:24.762321Z","caller":"traceutil/trace.go:171","msg":"trace[1884669787] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nlx9z; range_end:; response_count:1; response_revision:623; }","duration":"481.413117ms","start":"2026-04-07T01:26:24.280875Z","end":"2026-04-07T01:26:24.762289Z","steps":["trace[1884669787] 'agreement among raft nodes before linearized reading' (duration: 481.11861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:26:24.762373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:26:24.280844Z","time spent":"481.52054ms","remote":"127.0.0.1:39242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-nlx9z\" "} {"level":"warn","ts":"2026-04-07T01:27:07.224471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.180916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:07.224715Z","caller":"traceutil/trace.go:171","msg":"trace[2139162629] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"175.162927ms","start":"2026-04-07T01:27:07.049523Z","end":"2026-04-07T01:27:07.224686Z","steps":["trace[2139162629] 'process raft request' (duration: 36.687874ms)","trace[2139162629] 'compare' (duration: 138.009292ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:07.224818Z","caller":"traceutil/trace.go:171","msg":"trace[1768505695] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"174.899411ms","start":"2026-04-07T01:27:07.049747Z","end":"2026-04-07T01:27:07.224646Z","steps":["trace[1768505695] 'process raft request' (duration: 174.829759ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:27.266596Z","caller":"traceutil/trace.go:171","msg":"trace[499654480] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"108.963208ms","start":"2026-04-07T01:27:27.157598Z","end":"2026-04-07T01:27:27.266561Z","steps":["trace[499654480] 'process raft request' (duration: 108.793694ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:28.786307Z","caller":"traceutil/trace.go:171","msg":"trace[227955678] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"105.871498ms","start":"2026-04-07T01:27:28.680388Z","end":"2026-04-07T01:27:28.78626Z","steps":["trace[227955678] 'process raft request' (duration: 105.556831ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:52.816593Z","caller":"traceutil/trace.go:171","msg":"trace[388827546] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"271.058684ms","start":"2026-04-07T01:27:52.545501Z","end":"2026-04-07T01:27:52.816559Z","steps":["trace[388827546] 'read index received' (duration: 270.87667ms)","trace[388827546] 'applied index is now lower than readState.Index' (duration: 180.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:52.816709Z","caller":"traceutil/trace.go:171","msg":"trace[1680656206] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"363.926097ms","start":"2026-04-07T01:27:52.452762Z","end":"2026-04-07T01:27:52.816688Z","steps":["trace[1680656206] 'process raft request' (duration: 363.624561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:52.816858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:52.45274Z","time spent":"364.021329ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:52.8169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.017891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:27:52.816894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.259718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca16dc81-8f8a-4d3f-b016-640c2c936e42\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-07T01:27:52.816894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.368761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T01:27:52.816963Z","caller":"traceutil/trace.go:171","msg":"trace[1377573784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1825; }","duration":"130.123563ms","start":"2026-04-07T01:27:52.686826Z","end":"2026-04-07T01:27:52.81695Z","steps":["trace[1377573784] 'agreement among raft nodes before linearized reading' (duration: 130.034971ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:52.817026Z","caller":"traceutil/trace.go:171","msg":"trace[1430187260] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca16dc81-8f8a-4d3f-b016-640c2c936e42; range_end:; response_count:1; response_revision:1825; }","duration":"161.436132ms","start":"2026-04-07T01:27:52.65556Z","end":"2026-04-07T01:27:52.816996Z","steps":["trace[1430187260] 'agreement among raft nodes before linearized reading' (duration: 161.196877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:52.817028Z","caller":"traceutil/trace.go:171","msg":"trace[1978310591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1825; }","duration":"271.546964ms","start":"2026-04-07T01:27:52.545461Z","end":"2026-04-07T01:27:52.817008Z","steps":["trace[1978310591] 'agreement among raft nodes before linearized reading' (duration: 271.262498ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:53.480303Z","caller":"traceutil/trace.go:171","msg":"trace[1912739936] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"658.752774ms","start":"2026-04-07T01:27:52.821521Z","end":"2026-04-07T01:27:53.480274Z","steps":["trace[1912739936] 'process raft request' (duration: 658.615031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.480483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:52.821499Z","time spent":"658.892927ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:53.746193Z","caller":"traceutil/trace.go:171","msg":"trace[374879908] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"921.410452ms","start":"2026-04-07T01:27:52.824753Z","end":"2026-04-07T01:27:53.746164Z","steps":["trace[374879908] 'process raft request' (duration: 826.902913ms)","trace[374879908] 'compare' (duration: 94.146201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:53.746388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:52.824728Z","time spent":"921.591287ms","remote":"127.0.0.1:39242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:27:53.746415Z","caller":"traceutil/trace.go:171","msg":"trace[1864994531] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1897; }","duration":"723.494978ms","start":"2026-04-07T01:27:53.022901Z","end":"2026-04-07T01:27:53.746396Z","steps":["trace[1864994531] 'read index received' (duration: 457.413624ms)","trace[1864994531] 'applied index is now lower than readState.Index' (duration: 266.078904ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:53.746481Z","caller":"traceutil/trace.go:171","msg":"trace[176658336] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"321.601816ms","start":"2026-04-07T01:27:53.424846Z","end":"2026-04-07T01:27:53.746448Z","steps":["trace[176658336] 'process raft request' (duration: 321.36096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.746661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:53.424809Z","time spent":"321.762379ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:27:53.74668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.750104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T01:27:53.746728Z","caller":"traceutil/trace.go:171","msg":"trace[2082301288] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1829; }","duration":"723.822785ms","start":"2026-04-07T01:27:53.022885Z","end":"2026-04-07T01:27:53.746708Z","steps":["trace[2082301288] 'agreement among raft nodes before linearized reading' (duration: 723.645121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.746772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:53.022826Z","time spent":"723.935307ms","remote":"127.0.0.1:41522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T01:27:53.746863Z","caller":"traceutil/trace.go:171","msg":"trace[1848890701] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"869.141103ms","start":"2026-04-07T01:27:52.877143Z","end":"2026-04-07T01:27:53.746284Z","steps":["trace[1848890701] 'process raft request' (duration: 868.933718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.747218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.97257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:27:53.747261Z","caller":"traceutil/trace.go:171","msg":"trace[317518485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1830; }","duration":"300.050592ms","start":"2026-04-07T01:27:53.447198Z","end":"2026-04-07T01:27:53.747249Z","steps":["trace[317518485] 'agreement among raft nodes before linearized reading' (duration: 299.902129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.747279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.025622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-07T01:27:53.747297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:53.44717Z","time spent":"300.117103ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:27:53.747273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.879334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T01:27:53.74733Z","caller":"traceutil/trace.go:171","msg":"trace[149428915] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1830; }","duration":"305.129324ms","start":"2026-04-07T01:27:53.442187Z","end":"2026-04-07T01:27:53.747316Z","steps":["trace[149428915] 'agreement among raft nodes before linearized reading' (duration: 304.96703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:53.74735Z","caller":"traceutil/trace.go:171","msg":"trace[742272475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"338.025107ms","start":"2026-04-07T01:27:53.409312Z","end":"2026-04-07T01:27:53.747337Z","steps":["trace[742272475] 'agreement among raft nodes before linearized reading' (duration: 337.771441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.74737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:53.442158Z","time spent":"305.204965ms","remote":"127.0.0.1:41522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T01:27:53.74739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:53.409282Z","time spent":"338.098149ms","remote":"127.0.0.1:39308","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-04-07T01:27:53.747268Z","caller":"traceutil/trace.go:171","msg":"trace[1693539516] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"164.914209ms","start":"2026-04-07T01:27:53.582316Z","end":"2026-04-07T01:27:53.747231Z","steps":["trace[1693539516] 'process raft request' (duration: 164.610332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.747436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.590375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T01:27:53.747563Z","caller":"traceutil/trace.go:171","msg":"trace[6621621] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"221.766859ms","start":"2026-04-07T01:27:53.525765Z","end":"2026-04-07T01:27:53.747532Z","steps":["trace[6621621] 'agreement among raft nodes before linearized reading' (duration: 221.444422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.747393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.481284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:27:53.747681Z","caller":"traceutil/trace.go:171","msg":"trace[426560750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1830; }","duration":"301.848491ms","start":"2026-04-07T01:27:53.44581Z","end":"2026-04-07T01:27:53.747659Z","steps":["trace[426560750] 'agreement among raft nodes before linearized reading' (duration: 301.315879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:53.747727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:53.445797Z","time spent":"301.917943ms","remote":"127.0.0.1:39308","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-04-07T01:27:53.747231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:27:52.877117Z","time spent":"869.867798ms","remote":"127.0.0.1:39308","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-04-07T01:28:38.173911Z","caller":"traceutil/trace.go:171","msg":"trace[1235737766] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"247.607939ms","start":"2026-04-07T01:28:37.926277Z","end":"2026-04-07T01:28:38.173885Z","steps":["trace[1235737766] 'process raft request' (duration: 247.467815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:38.20316Z","caller":"traceutil/trace.go:171","msg":"trace[299058984] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2206; }","duration":"228.695812ms","start":"2026-04-07T01:28:37.974434Z","end":"2026-04-07T01:28:38.203129Z","steps":["trace[299058984] 'read index received' (duration: 199.284767ms)","trace[299058984] 'applied index is now lower than readState.Index' (duration: 29.410005ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:28:38.203285Z","caller":"traceutil/trace.go:171","msg":"trace[877579992] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"263.013702ms","start":"2026-04-07T01:28:37.94023Z","end":"2026-04-07T01:28:38.203244Z","steps":["trace[877579992] 'process raft request' (duration: 262.766316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:38.203416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.968539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T01:28:38.203469Z","caller":"traceutil/trace.go:171","msg":"trace[1659052642] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2127; }","duration":"229.06182ms","start":"2026-04-07T01:28:37.974389Z","end":"2026-04-07T01:28:38.20345Z","steps":["trace[1659052642] 'agreement among raft nodes before linearized reading' (duration: 228.857865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:38.203467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.997484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T01:28:38.203467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.617742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T01:28:38.203526Z","caller":"traceutil/trace.go:171","msg":"trace[518550869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2127; }","duration":"222.118387ms","start":"2026-04-07T01:28:37.981392Z","end":"2026-04-07T01:28:38.20351Z","steps":["trace[518550869] 'agreement among raft nodes before linearized reading' (duration: 221.913052ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:38.203537Z","caller":"traceutil/trace.go:171","msg":"trace[1232952466] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2127; }","duration":"118.700954ms","start":"2026-04-07T01:28:38.084817Z","end":"2026-04-07T01:28:38.203518Z","steps":["trace[1232952466] 'agreement among raft nodes before linearized reading' (duration: 118.512879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:38.203563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.797735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T01:28:38.203623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.338895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T01:28:38.20367Z","caller":"traceutil/trace.go:171","msg":"trace[34169138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2127; }","duration":"148.944038ms","start":"2026-04-07T01:28:38.054704Z","end":"2026-04-07T01:28:38.203648Z","steps":["trace[34169138] 'agreement among raft nodes before linearized reading' (duration: 148.707902ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:38.203723Z","caller":"traceutil/trace.go:171","msg":"trace[1460097091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2127; }","duration":"154.496329ms","start":"2026-04-07T01:28:38.049207Z","end":"2026-04-07T01:28:38.203704Z","steps":["trace[1460097091] 'agreement among raft nodes before linearized reading' (duration: 154.236973ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:44.452245Z","caller":"traceutil/trace.go:171","msg":"trace[606539598] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"183.268349ms","start":"2026-04-07T01:28:44.268947Z","end":"2026-04-07T01:28:44.452215Z","steps":["trace[606539598] 'process raft request' (duration: 183.094655ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:44.452591Z","caller":"traceutil/trace.go:171","msg":"trace[201996103] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"183.208978ms","start":"2026-04-07T01:28:44.269354Z","end":"2026-04-07T01:28:44.452563Z","steps":["trace[201996103] 'process raft request' (duration: 183.084095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:44.45261Z","caller":"traceutil/trace.go:171","msg":"trace[623796256] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"183.2999ms","start":"2026-04-07T01:28:44.269279Z","end":"2026-04-07T01:28:44.452579Z","steps":["trace[623796256] 'process raft request' (duration: 182.89399ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:28:44.505693Z","caller":"traceutil/trace.go:171","msg":"trace[805259091] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"144.434531ms","start":"2026-04-07T01:28:44.361243Z","end":"2026-04-07T01:28:44.505677Z","steps":["trace[805259091] 'process raft request' (duration: 144.330109ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:32.237963Z","caller":"traceutil/trace.go:171","msg":"trace[711399598] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"187.036815ms","start":"2026-04-07T01:29:32.050892Z","end":"2026-04-07T01:29:32.237929Z","steps":["trace[711399598] 'process raft request' (duration: 186.736978ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:30:06.525512Z","caller":"traceutil/trace.go:171","msg":"trace[1835372321] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"228.704125ms","start":"2026-04-07T01:30:06.29678Z","end":"2026-04-07T01:30:06.525484Z","steps":["trace[1835372321] 'process raft request' (duration: 228.462099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:30:06.836723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.073968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:30:06.83705Z","caller":"traceutil/trace.go:171","msg":"trace[1764755366] linearizableReadLoop","detail":"{readStateIndex:2908; appliedIndex:2906; }","duration":"133.458816ms","start":"2026-04-07T01:30:06.703569Z","end":"2026-04-07T01:30:06.837027Z","steps":["trace[1764755366] 'read index received' (duration: 17.066701ms)","trace[1764755366] 'applied index is now lower than readState.Index' (duration: 116.387265ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:30:06.837083Z","caller":"traceutil/trace.go:171","msg":"trace[14589689] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"256.556008ms","start":"2026-04-07T01:30:06.580494Z","end":"2026-04-07T01:30:06.83705Z","steps":["trace[14589689] 'process raft request' (duration: 140.075101ms)","trace[14589689] 'compare' (duration: 115.808182ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:30:06.837178Z","caller":"traceutil/trace.go:171","msg":"trace[752029500] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"254.658614ms","start":"2026-04-07T01:30:06.582487Z","end":"2026-04-07T01:30:06.837145Z","steps":["trace[752029500] 'process raft request' (duration: 254.446479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:30:06.837426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.857675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T01:30:06.837515Z","caller":"traceutil/trace.go:171","msg":"trace[504327138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2801; }","duration":"133.984058ms","start":"2026-04-07T01:30:06.703518Z","end":"2026-04-07T01:30:06.837502Z","steps":["trace[504327138] 'agreement among raft nodes before linearized reading' (duration: 133.63603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:37.767526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.458372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T01:32:37.767624Z","caller":"traceutil/trace.go:171","msg":"trace[949540711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3796; }","duration":"111.598125ms","start":"2026-04-07T01:32:37.656007Z","end":"2026-04-07T01:32:37.767605Z","steps":["trace[949540711] 'range keys from in-memory index tree' (duration: 111.302578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:37.767783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.054902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T01:32:37.767875Z","caller":"traceutil/trace.go:171","msg":"trace[1506408848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3796; }","duration":"111.191135ms","start":"2026-04-07T01:32:37.656667Z","end":"2026-04-07T01:32:37.767858Z","steps":["trace[1506408848] 'range keys from in-memory index tree' (duration: 110.894899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:37.768057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.978885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T01:32:37.768146Z","caller":"traceutil/trace.go:171","msg":"trace[1667695706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3796; }","duration":"115.112748ms","start":"2026-04-07T01:32:37.653017Z","end":"2026-04-07T01:32:37.768129Z","steps":["trace[1667695706] 'range keys from in-memory index tree' (duration: 114.713859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:37.768283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.239543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-07T01:32:37.768379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.297612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T01:32:37.768439Z","caller":"traceutil/trace.go:171","msg":"trace[937629362] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3796; }","duration":"104.368804ms","start":"2026-04-07T01:32:37.664045Z","end":"2026-04-07T01:32:37.768414Z","steps":["trace[937629362] 'count revisions from in-memory index tree' (duration: 104.223361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:37.768373Z","caller":"traceutil/trace.go:171","msg":"trace[938307402] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3796; }","duration":"110.404557ms","start":"2026-04-07T01:32:37.657944Z","end":"2026-04-07T01:32:37.768349Z","steps":["trace[938307402] 'range keys from in-memory index tree' (duration: 109.67856ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:54.739367Z","caller":"traceutil/trace.go:171","msg":"trace[1059570044] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"181.952696ms","start":"2026-04-07T01:33:54.557379Z","end":"2026-04-07T01:33:54.739332Z","steps":["trace[1059570044] 'process raft request' (duration: 125.619169ms)","trace[1059570044] 'compare' (duration: 56.180944ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:54.789152Z","caller":"traceutil/trace.go:171","msg":"trace[1655939281] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4889; }","duration":"210.79201ms","start":"2026-04-07T01:33:54.578324Z","end":"2026-04-07T01:33:54.789116Z","steps":["trace[1655939281] 'read index received' (duration: 104.686564ms)","trace[1655939281] 'applied index is now lower than readState.Index' (duration: 106.103926ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:54.789195Z","caller":"traceutil/trace.go:171","msg":"trace[543475695] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"231.604042ms","start":"2026-04-07T01:33:54.557542Z","end":"2026-04-07T01:33:54.789146Z","steps":["trace[543475695] 'process raft request' (duration: 231.243554ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:54.789242Z","caller":"traceutil/trace.go:171","msg":"trace[615342893] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"230.965008ms","start":"2026-04-07T01:33:54.558255Z","end":"2026-04-07T01:33:54.78922Z","steps":["trace[615342893] 'process raft request' (duration: 230.736282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:54.789426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.242989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:33:54.789474Z","caller":"traceutil/trace.go:171","msg":"trace[2073611330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4712; }","duration":"102.30553ms","start":"2026-04-07T01:33:54.687159Z","end":"2026-04-07T01:33:54.789464Z","steps":["trace[2073611330] 'agreement among raft nodes before linearized reading' (duration: 102.216108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:54.789479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.132398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-07T01:33:54.78953Z","caller":"traceutil/trace.go:171","msg":"trace[235707378] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4712; }","duration":"211.25623ms","start":"2026-04-07T01:33:54.57826Z","end":"2026-04-07T01:33:54.789517Z","steps":["trace[235707378] 'agreement among raft nodes before linearized reading' (duration: 211.048296ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:01.340443Z","caller":"traceutil/trace.go:171","msg":"trace[1367239686] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"107.032417ms","start":"2026-04-07T01:34:01.233372Z","end":"2026-04-07T01:34:01.340404Z","steps":["trace[1367239686] 'process raft request' (duration: 93.305265ms)","trace[1367239686] 'compare' (duration: 13.178239ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:34:01.546004Z","caller":"traceutil/trace.go:171","msg":"trace[1832975461] linearizableReadLoop","detail":"{readStateIndex:5045; appliedIndex:5044; }","duration":"132.416083ms","start":"2026-04-07T01:34:01.413563Z","end":"2026-04-07T01:34:01.545979Z","steps":["trace[1832975461] 'read index received' (duration: 132.154677ms)","trace[1832975461] 'applied index is now lower than readState.Index' (duration: 260.416µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:34:01.546071Z","caller":"traceutil/trace.go:171","msg":"trace[2029399892] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"134.584022ms","start":"2026-04-07T01:34:01.411441Z","end":"2026-04-07T01:34:01.546025Z","steps":["trace[2029399892] 'process raft request' (duration: 134.238784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:01.54619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.64984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:34:01.546208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.626998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/loki-memberlist\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:34:01.546285Z","caller":"traceutil/trace.go:171","msg":"trace[224939172] range","detail":"{range_begin:/registry/services/specs/monitoring/loki-memberlist; range_end:; response_count:0; response_revision:4857; }","duration":"132.74155ms","start":"2026-04-07T01:34:01.413527Z","end":"2026-04-07T01:34:01.546269Z","steps":["trace[224939172] 'agreement among raft nodes before linearized reading' (duration: 132.584747ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:01.546233Z","caller":"traceutil/trace.go:171","msg":"trace[8331942] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4857; }","duration":"114.755422ms","start":"2026-04-07T01:34:01.431467Z","end":"2026-04-07T01:34:01.546222Z","steps":["trace[8331942] 'agreement among raft nodes before linearized reading' (duration: 114.66256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:07.570124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.719934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:34:07.570232Z","caller":"traceutil/trace.go:171","msg":"trace[623004463] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5059; }","duration":"126.895347ms","start":"2026-04-07T01:34:07.443315Z","end":"2026-04-07T01:34:07.570211Z","steps":["trace[623004463] 'count revisions from in-memory index tree' (duration: 126.632561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:07.871253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.712287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T01:34:07.871331Z","caller":"traceutil/trace.go:171","msg":"trace[1448417350] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5060; }","duration":"135.83354ms","start":"2026-04-07T01:34:07.735476Z","end":"2026-04-07T01:34:07.87131Z","steps":["trace[1448417350] 'range keys from in-memory index tree' (duration: 135.458841ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:07.871284Z","caller":"traceutil/trace.go:171","msg":"trace[55908712] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"126.829337ms","start":"2026-04-07T01:34:07.744416Z","end":"2026-04-07T01:34:07.871245Z","steps":["trace[55908712] 'process raft request' (duration: 119.683125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:07.871312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.155116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"warn","ts":"2026-04-07T01:34:07.871325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.978703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-07T01:34:07.871413Z","caller":"traceutil/trace.go:171","msg":"trace[381377372] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5060; }","duration":"180.288028ms","start":"2026-04-07T01:34:07.691104Z","end":"2026-04-07T01:34:07.871392Z","steps":["trace[381377372] 'range keys from in-memory index tree' (duration: 179.997952ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:07.87145Z","caller":"traceutil/trace.go:171","msg":"trace[2072977998] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5060; }","duration":"177.160998ms","start":"2026-04-07T01:34:07.694264Z","end":"2026-04-07T01:34:07.871425Z","steps":["trace[2072977998] 'range keys from in-memory index tree' (duration: 176.638276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:07.871664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.981105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:34:07.871791Z","caller":"traceutil/trace.go:171","msg":"trace[1043893524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5060; }","duration":"185.177879ms","start":"2026-04-07T01:34:07.68658Z","end":"2026-04-07T01:34:07.871758Z","steps":["trace[1043893524] 'range keys from in-memory index tree' (duration: 184.78299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:07.871854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.444123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5485f6c6fc-z7znd.18a3ef2b23f0e88c\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-07T01:34:07.871972Z","caller":"traceutil/trace.go:171","msg":"trace[240534067] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5485f6c6fc-z7znd.18a3ef2b23f0e88c; range_end:; response_count:1; response_revision:5060; }","duration":"173.612797ms","start":"2026-04-07T01:34:07.698334Z","end":"2026-04-07T01:34:07.871947Z","steps":["trace[240534067] 'range keys from in-memory index tree' (duration: 173.214058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:07.905153Z","caller":"traceutil/trace.go:171","msg":"trace[1989171895] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"153.881099ms","start":"2026-04-07T01:34:07.751249Z","end":"2026-04-07T01:34:07.90513Z","steps":["trace[1989171895] 'process raft request' (duration: 153.692095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:07.905628Z","caller":"traceutil/trace.go:171","msg":"trace[535481072] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"154.249348ms","start":"2026-04-07T01:34:07.751368Z","end":"2026-04-07T01:34:07.905617Z","steps":["trace[535481072] 'process raft request' (duration: 153.716256ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:07.905632Z","caller":"traceutil/trace.go:171","msg":"trace[9454900] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5253; }","duration":"144.285312ms","start":"2026-04-07T01:34:07.76131Z","end":"2026-04-07T01:34:07.905596Z","steps":["trace[9454900] 'read index received' (duration: 102.804801ms)","trace[9454900] 'applied index is now lower than readState.Index' (duration: 41.478731ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:34:07.905774Z","caller":"traceutil/trace.go:171","msg":"trace[1240381366] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"144.711092ms","start":"2026-04-07T01:34:07.761051Z","end":"2026-04-07T01:34:07.905762Z","steps":["trace[1240381366] 'process raft request' (duration: 144.409555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:07.905864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.536348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:34:07.905905Z","caller":"traceutil/trace.go:171","msg":"trace[1531516284] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5064; }","duration":"144.60305ms","start":"2026-04-07T01:34:07.761291Z","end":"2026-04-07T01:34:07.905894Z","steps":["trace[1531516284] 'agreement among raft nodes before linearized reading' (duration: 144.486627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:08.002724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.620079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T01:34:08.003213Z","caller":"traceutil/trace.go:171","msg":"trace[1138812404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5066; }","duration":"160.161472ms","start":"2026-04-07T01:34:07.84303Z","end":"2026-04-07T01:34:08.003191Z","steps":["trace[1138812404] 'agreement among raft nodes before linearized reading' (duration: 159.546578ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.002914Z","caller":"traceutil/trace.go:171","msg":"trace[1606658076] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"118.564288ms","start":"2026-04-07T01:34:07.884307Z","end":"2026-04-07T01:34:08.002871Z","steps":["trace[1606658076] 'process raft request' (duration: 118.18576ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.002955Z","caller":"traceutil/trace.go:171","msg":"trace[1601504410] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"128.648597ms","start":"2026-04-07T01:34:07.874254Z","end":"2026-04-07T01:34:08.002902Z","steps":["trace[1601504410] 'process raft request' (duration: 126.456807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:08.003019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.43177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-30b745b2-825b-4f4a-bf8e-f85184ed7e81\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-07T01:34:08.003592Z","caller":"traceutil/trace.go:171","msg":"trace[800040621] range","detail":"{range_begin:/registry/persistentvolumes/pvc-30b745b2-825b-4f4a-bf8e-f85184ed7e81; range_end:; response_count:1; response_revision:5066; }","duration":"127.993033ms","start":"2026-04-07T01:34:07.875552Z","end":"2026-04-07T01:34:08.003545Z","steps":["trace[800040621] 'agreement among raft nodes before linearized reading' (duration: 127.386319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:08.003092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.722242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:34:08.003121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.723009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:34:08.003911Z","caller":"traceutil/trace.go:171","msg":"trace[1428759239] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5066; }","duration":"103.563918ms","start":"2026-04-07T01:34:07.900313Z","end":"2026-04-07T01:34:08.003877Z","steps":["trace[1428759239] 'agreement among raft nodes before linearized reading' (duration: 102.719069ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.005037Z","caller":"traceutil/trace.go:171","msg":"trace[1214833341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5066; }","duration":"131.533143ms","start":"2026-04-07T01:34:07.873334Z","end":"2026-04-07T01:34:08.004867Z","steps":["trace[1214833341] 'agreement among raft nodes before linearized reading' (duration: 129.66881ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.193412Z","caller":"traceutil/trace.go:171","msg":"trace[1025819362] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"179.691124ms","start":"2026-04-07T01:34:08.013686Z","end":"2026-04-07T01:34:08.193377Z","steps":["trace[1025819362] 'process raft request' (duration: 159.965787ms)","trace[1025819362] 'compare' (duration: 19.428761ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:34:08.196686Z","caller":"traceutil/trace.go:171","msg":"trace[666495802] linearizableReadLoop","detail":"{readStateIndex:5261; appliedIndex:5259; }","duration":"182.772844ms","start":"2026-04-07T01:34:08.013898Z","end":"2026-04-07T01:34:08.196671Z","steps":["trace[666495802] 'read index received' (duration: 159.711931ms)","trace[666495802] 'applied index is now lower than readState.Index' (duration: 23.060123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:34:08.196853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.933068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6a01093f-acea-4ca4-a4b2-23b814122cd2\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-07T01:34:08.196894Z","caller":"traceutil/trace.go:171","msg":"trace[2052461570] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6a01093f-acea-4ca4-a4b2-23b814122cd2; range_end:; response_count:1; response_revision:5072; }","duration":"182.99856ms","start":"2026-04-07T01:34:08.013882Z","end":"2026-04-07T01:34:08.196881Z","steps":["trace[2052461570] 'agreement among raft nodes before linearized reading' (duration: 182.849546ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.198589Z","caller":"traceutil/trace.go:171","msg":"trace[888991715] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"184.77848ms","start":"2026-04-07T01:34:08.013796Z","end":"2026-04-07T01:34:08.198574Z","steps":["trace[888991715] 'process raft request' (duration: 182.487688ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.198834Z","caller":"traceutil/trace.go:171","msg":"trace[1663326264] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"184.943584ms","start":"2026-04-07T01:34:08.01388Z","end":"2026-04-07T01:34:08.198824Z","steps":["trace[1663326264] 'process raft request' (duration: 182.519659ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.198971Z","caller":"traceutil/trace.go:171","msg":"trace[585518494] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"185.023845ms","start":"2026-04-07T01:34:08.013936Z","end":"2026-04-07T01:34:08.19896Z","steps":["trace[585518494] 'process raft request' (duration: 182.542439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.199159Z","caller":"traceutil/trace.go:171","msg":"trace[411379652] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"184.818482ms","start":"2026-04-07T01:34:08.014332Z","end":"2026-04-07T01:34:08.19915Z","steps":["trace[411379652] 'process raft request' (duration: 182.178981ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:08.19937Z","caller":"traceutil/trace.go:171","msg":"trace[1778561646] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"176.456231ms","start":"2026-04-07T01:34:08.022902Z","end":"2026-04-07T01:34:08.199358Z","steps":["trace[1778561646] 'process raft request' (duration: 173.660468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:21.750707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.533157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bdd05c8a-a383-42d0-8da4-45026b02fc36\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T01:34:21.750813Z","caller":"traceutil/trace.go:171","msg":"trace[1734546944] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bdd05c8a-a383-42d0-8da4-45026b02fc36; range_end:; response_count:1; response_revision:5315; }","duration":"114.675521ms","start":"2026-04-07T01:34:21.636107Z","end":"2026-04-07T01:34:21.750782Z","steps":["trace[1734546944] 'range keys from in-memory index tree' (duration: 114.342353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:21.750858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.170641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-04-07T01:34:21.751227Z","caller":"traceutil/trace.go:171","msg":"trace[1034079044] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5315; }","duration":"111.54543ms","start":"2026-04-07T01:34:21.639633Z","end":"2026-04-07T01:34:21.751178Z","steps":["trace[1034079044] 'range keys from in-memory index tree' (duration: 111.032648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:21.751132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.254296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a3ef2e972b2331\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-04-07T01:34:21.75147Z","caller":"traceutil/trace.go:171","msg":"trace[1712697553] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a3ef2e972b2331; range_end:; response_count:1; response_revision:5315; }","duration":"112.628694ms","start":"2026-04-07T01:34:21.638818Z","end":"2026-04-07T01:34:21.751447Z","steps":["trace[1712697553] 'range keys from in-memory index tree' (duration: 111.850366ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:21.781965Z","caller":"traceutil/trace.go:171","msg":"trace[173959353] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"104.584421ms","start":"2026-04-07T01:34:21.677362Z","end":"2026-04-07T01:34:21.781946Z","steps":["trace[173959353] 'process raft request' (duration: 104.397737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:34:22.962893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.614176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bdd05c8a-a383-42d0-8da4-45026b02fc36\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-07T01:34:22.962993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.957571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:34:22.963037Z","caller":"traceutil/trace.go:171","msg":"trace[1097101246] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bdd05c8a-a383-42d0-8da4-45026b02fc36; range_end:; response_count:1; response_revision:5339; }","duration":"113.806081ms","start":"2026-04-07T01:34:22.849212Z","end":"2026-04-07T01:34:22.963018Z","steps":["trace[1097101246] 'range keys from in-memory index tree' (duration: 113.35358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:22.963062Z","caller":"traceutil/trace.go:171","msg":"trace[1513530858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5339; }","duration":"277.060582ms","start":"2026-04-07T01:34:22.685984Z","end":"2026-04-07T01:34:22.963044Z","steps":["trace[1513530858] 'range keys from in-memory index tree' (duration: 276.875068ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:35.114009Z","caller":"traceutil/trace.go:171","msg":"trace[1072752396] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"103.223751ms","start":"2026-04-07T01:34:35.010764Z","end":"2026-04-07T01:34:35.113988Z","steps":["trace[1072752396] 'process raft request' (duration: 102.77326ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:35.219843Z","caller":"traceutil/trace.go:171","msg":"trace[1344796609] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"100.075669ms","start":"2026-04-07T01:34:35.119736Z","end":"2026-04-07T01:34:35.219811Z","steps":["trace[1344796609] 'process raft request' (duration: 99.631819ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:35:46.428438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3017} {"level":"info","ts":"2026-04-07T01:35:46.50963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3017,"took":"79.685357ms","hash":824982194,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":31842304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:35:46.509786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":824982194,"revision":3017,"compact-revision":-1} {"level":"info","ts":"2026-04-07T01:40:22.465Z","caller":"traceutil/trace.go:171","msg":"trace[741743823] linearizableReadLoop","detail":"{readStateIndex:8458; appliedIndex:8457; }","duration":"134.220844ms","start":"2026-04-07T01:40:22.330755Z","end":"2026-04-07T01:40:22.464976Z","steps":["trace[741743823] 'read index received' (duration: 134.03059ms)","trace[741743823] 'applied index is now lower than readState.Index' (duration: 189.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:22.465027Z","caller":"traceutil/trace.go:171","msg":"trace[1936982654] transaction","detail":"{read_only:false; response_revision:8172; number_of_response:1; }","duration":"355.576384ms","start":"2026-04-07T01:40:22.109432Z","end":"2026-04-07T01:40:22.465009Z","steps":["trace[1936982654] 'process raft request' (duration: 355.365859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:22.465171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.413518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T01:40:22.465185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:40:22.109411Z","time spent":"355.711466ms","remote":"127.0.0.1:39308","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-04-07T01:40:22.465208Z","caller":"traceutil/trace.go:171","msg":"trace[1829569077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8172; }","duration":"134.49145ms","start":"2026-04-07T01:40:22.330708Z","end":"2026-04-07T01:40:22.4652Z","steps":["trace[1829569077] 'agreement among raft nodes before linearized reading' (duration: 134.356097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:22.465326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.362382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T01:40:22.465348Z","caller":"traceutil/trace.go:171","msg":"trace[1670662438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8172; }","duration":"122.407383ms","start":"2026-04-07T01:40:22.342932Z","end":"2026-04-07T01:40:22.465339Z","steps":["trace[1670662438] 'agreement among raft nodes before linearized reading' (duration: 122.345782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:22.465449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.249975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T01:40:22.465557Z","caller":"traceutil/trace.go:171","msg":"trace[1942592578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8172; }","duration":"127.397507ms","start":"2026-04-07T01:40:22.33812Z","end":"2026-04-07T01:40:22.465517Z","steps":["trace[1942592578] 'agreement among raft nodes before linearized reading' (duration: 127.099711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:22.641046Z","caller":"traceutil/trace.go:171","msg":"trace[907824985] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"171.821825ms","start":"2026-04-07T01:40:22.469189Z","end":"2026-04-07T01:40:22.641011Z","steps":["trace[907824985] 'process raft request' (duration: 166.821931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:22.641819Z","caller":"traceutil/trace.go:171","msg":"trace[1173633686] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"171.868506ms","start":"2026-04-07T01:40:22.469928Z","end":"2026-04-07T01:40:22.641796Z","steps":["trace[1173633686] 'process raft request' (duration: 171.785805ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:22.642045Z","caller":"traceutil/trace.go:171","msg":"trace[1074528550] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"172.434049ms","start":"2026-04-07T01:40:22.469558Z","end":"2026-04-07T01:40:22.641992Z","steps":["trace[1074528550] 'process raft request' (duration: 172.014089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:22.642087Z","caller":"traceutil/trace.go:171","msg":"trace[912923632] transaction","detail":"{read_only:false; response_revision:8176; number_of_response:1; }","duration":"171.972359ms","start":"2026-04-07T01:40:22.470088Z","end":"2026-04-07T01:40:22.642061Z","steps":["trace[912923632] 'process raft request' (duration: 171.665221ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:33.991305Z","caller":"traceutil/trace.go:171","msg":"trace[886581506] transaction","detail":"{read_only:false; response_revision:8237; number_of_response:1; }","duration":"295.155119ms","start":"2026-04-07T01:40:33.696121Z","end":"2026-04-07T01:40:33.991276Z","steps":["trace[886581506] 'process raft request' (duration: 294.937014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:35.191881Z","caller":"traceutil/trace.go:171","msg":"trace[436792730] linearizableReadLoop","detail":"{readStateIndex:8528; appliedIndex:8527; }","duration":"475.166342ms","start":"2026-04-07T01:40:34.71669Z","end":"2026-04-07T01:40:35.191857Z","steps":["trace[436792730] 'read index received' (duration: 475.029029ms)","trace[436792730] 'applied index is now lower than readState.Index' (duration: 136.303µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:35.191937Z","caller":"traceutil/trace.go:171","msg":"trace[443179702] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"486.832298ms","start":"2026-04-07T01:40:34.705074Z","end":"2026-04-07T01:40:35.191906Z","steps":["trace[443179702] 'process raft request' (duration: 486.629674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:35.192112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:40:34.705054Z","time spent":"486.950391ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:40:35.192137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.428768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T01:40:35.192181Z","caller":"traceutil/trace.go:171","msg":"trace[826309088] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8239; }","duration":"475.509509ms","start":"2026-04-07T01:40:34.716657Z","end":"2026-04-07T01:40:35.192167Z","steps":["trace[826309088] 'agreement among raft nodes before linearized reading' (duration: 475.326025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:35.192217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:40:34.71665Z","time spent":"475.55963ms","remote":"127.0.0.1:39308","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":"warn","ts":"2026-04-07T01:40:35.270486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.695414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T01:40:35.270546Z","caller":"traceutil/trace.go:171","msg":"trace[384850267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8240; }","duration":"103.805777ms","start":"2026-04-07T01:40:35.166726Z","end":"2026-04-07T01:40:35.270532Z","steps":["trace[384850267] 'agreement among raft nodes before linearized reading' (duration: 103.680614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:35.270496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.984708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T01:40:35.270694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.500936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T01:40:35.270781Z","caller":"traceutil/trace.go:171","msg":"trace[2075755653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8240; }","duration":"553.630288ms","start":"2026-04-07T01:40:34.717131Z","end":"2026-04-07T01:40:35.270762Z","steps":["trace[2075755653] 'agreement among raft nodes before linearized reading' (duration: 553.348182ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:35.270739Z","caller":"traceutil/trace.go:171","msg":"trace[1548728738] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8240; }","duration":"527.162542ms","start":"2026-04-07T01:40:34.743465Z","end":"2026-04-07T01:40:35.270627Z","steps":["trace[1548728738] 'agreement among raft nodes before linearized reading' (duration: 526.907316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:35.270829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:40:34.717114Z","time spent":"553.70718ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:40:35.270905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:40:34.743457Z","time spent":"527.383887ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":111,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:40:35.271092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.782682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T01:40:35.271238Z","caller":"traceutil/trace.go:171","msg":"trace[804857488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8240; }","duration":"554.109679ms","start":"2026-04-07T01:40:34.717102Z","end":"2026-04-07T01:40:35.271211Z","steps":["trace[804857488] 'agreement among raft nodes before linearized reading' (duration: 553.206699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:35.271372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:40:34.717065Z","time spent":"554.293654ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T01:40:46.434988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6134} {"level":"info","ts":"2026-04-07T01:40:46.534566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6134,"took":"96.999121ms","hash":1612205911,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":23236608,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T01:40:46.534647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1612205911,"revision":6134,"compact-revision":3017} {"level":"info","ts":"2026-04-07T01:41:03.581542Z","caller":"traceutil/trace.go:171","msg":"trace[70694244] linearizableReadLoop","detail":"{readStateIndex:8742; appliedIndex:8741; }","duration":"226.115029ms","start":"2026-04-07T01:41:03.355404Z","end":"2026-04-07T01:41:03.581519Z","steps":["trace[70694244] 'read index received' (duration: 226.026357ms)","trace[70694244] 'applied index is now lower than readState.Index' (duration: 86.992µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:41:03.581654Z","caller":"traceutil/trace.go:171","msg":"trace[1308046309] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"252.404791ms","start":"2026-04-07T01:41:03.329219Z","end":"2026-04-07T01:41:03.581623Z","steps":["trace[1308046309] 'process raft request' (duration: 252.137725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:03.581728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.300933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:41:03.581796Z","caller":"traceutil/trace.go:171","msg":"trace[1526663543] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8444; }","duration":"226.419806ms","start":"2026-04-07T01:41:03.355357Z","end":"2026-04-07T01:41:03.581777Z","steps":["trace[1526663543] 'agreement among raft nodes before linearized reading' (duration: 226.306243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:03.877321Z","caller":"traceutil/trace.go:171","msg":"trace[1490562622] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"415.382083ms","start":"2026-04-07T01:41:03.461914Z","end":"2026-04-07T01:41:03.877296Z","steps":["trace[1490562622] 'process raft request' (duration: 391.181809ms)","trace[1490562622] 'compare' (duration: 24.047761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:41:03.877452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.461887Z","time spent":"415.498875ms","remote":"127.0.0.1:39308","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-04-07T01:41:03.877534Z","caller":"traceutil/trace.go:171","msg":"trace[678934180] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"341.298686ms","start":"2026-04-07T01:41:03.536203Z","end":"2026-04-07T01:41:03.877502Z","steps":["trace[678934180] 'process raft request' (duration: 341.05501ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:03.877605Z","caller":"traceutil/trace.go:171","msg":"trace[550536068] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"335.248158ms","start":"2026-04-07T01:41:03.542331Z","end":"2026-04-07T01:41:03.877579Z","steps":["trace[550536068] 'process raft request' (duration: 335.068074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:03.877688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.536172Z","time spent":"341.436419ms","remote":"127.0.0.1:39308","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-04-07T01:41:03.877776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.542311Z","time spent":"335.378571ms","remote":"127.0.0.1:39308","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-04-07T01:41:03.877786Z","caller":"traceutil/trace.go:171","msg":"trace[1299182944] linearizableReadLoop","detail":"{readStateIndex:8746; appliedIndex:8742; }","duration":"296.131322ms","start":"2026-04-07T01:41:03.581634Z","end":"2026-04-07T01:41:03.877765Z","steps":["trace[1299182944] 'read index received' (duration: 271.439657ms)","trace[1299182944] 'applied index is now lower than readState.Index' (duration: 24.690565ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:41:03.877821Z","caller":"traceutil/trace.go:171","msg":"trace[1489924952] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"324.488972ms","start":"2026-04-07T01:41:03.5533Z","end":"2026-04-07T01:41:03.877789Z","steps":["trace[1489924952] 'process raft request' (duration: 324.218055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:03.877908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.31995ms","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-04-07T01:41:03.877908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.782368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-07T01:41:03.877929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.981737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T01:41:03.877943Z","caller":"traceutil/trace.go:171","msg":"trace[841005461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8448; }","duration":"404.390482ms","start":"2026-04-07T01:41:03.473542Z","end":"2026-04-07T01:41:03.877933Z","steps":["trace[841005461] 'agreement among raft nodes before linearized reading' (duration: 404.251119ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:03.877951Z","caller":"traceutil/trace.go:171","msg":"trace[1548524968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8448; }","duration":"403.858259ms","start":"2026-04-07T01:41:03.474081Z","end":"2026-04-07T01:41:03.877939Z","steps":["trace[1548524968] 'agreement among raft nodes before linearized reading' (duration: 403.689825ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:03.877966Z","caller":"traceutil/trace.go:171","msg":"trace[503767243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8448; }","duration":"440.044828ms","start":"2026-04-07T01:41:03.437909Z","end":"2026-04-07T01:41:03.877954Z","steps":["trace[503767243] 'agreement among raft nodes before linearized reading' (duration: 439.917295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:03.877971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.473521Z","time spent":"404.444373ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T01:41:03.87798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.474064Z","time spent":"403.911941ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T01:41:03.877975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.339619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-07T01:41:03.877992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.161408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:41:03.877996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.437891Z","time spent":"440.095709ms","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T01:41:03.878008Z","caller":"traceutil/trace.go:171","msg":"trace[470133650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8448; }","duration":"191.212688ms","start":"2026-04-07T01:41:03.686791Z","end":"2026-04-07T01:41:03.878003Z","steps":["trace[470133650] 'agreement among raft nodes before linearized reading' (duration: 191.182208ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:03.878016Z","caller":"traceutil/trace.go:171","msg":"trace[1274370640] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8448; }","duration":"476.41528ms","start":"2026-04-07T01:41:03.401586Z","end":"2026-04-07T01:41:03.878002Z","steps":["trace[1274370640] 'agreement among raft nodes before linearized reading' (duration: 476.288858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:03.877949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.553284Z","time spent":"324.589473ms","remote":"127.0.0.1:39308","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-04-07T01:41:03.878033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.603261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-85c4d64544-jpgvv.18a3ef8097a265a1\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-04-07T01:41:03.878048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:41:03.401548Z","time spent":"476.492882ms","remote":"127.0.0.1:39308","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":"warn","ts":"2026-04-07T01:41:03.878035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.755517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zxtzm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T01:41:03.878079Z","caller":"traceutil/trace.go:171","msg":"trace[2096812682] range","detail":"{range_begin:/registry/events/openstack/barbican-api-85c4d64544-jpgvv.18a3ef8097a265a1; range_end:; response_count:1; response_revision:8448; }","duration":"185.676572ms","start":"2026-04-07T01:41:03.692387Z","end":"2026-04-07T01:41:03.878064Z","steps":["trace[2096812682] 'agreement among raft nodes before linearized reading' (duration: 185.558359ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:03.878097Z","caller":"traceutil/trace.go:171","msg":"trace[1970573710] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zxtzm; range_end:; response_count:1; response_revision:8448; }","duration":"241.862839ms","start":"2026-04-07T01:41:03.636217Z","end":"2026-04-07T01:41:03.87808Z","steps":["trace[1970573710] 'agreement among raft nodes before linearized reading' (duration: 241.701716ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:16.219276Z","caller":"traceutil/trace.go:171","msg":"trace[128401622] linearizableReadLoop","detail":"{readStateIndex:8853; appliedIndex:8851; }","duration":"256.895724ms","start":"2026-04-07T01:41:15.962354Z","end":"2026-04-07T01:41:16.21925Z","steps":["trace[128401622] 'read index received' (duration: 234.842599ms)","trace[128401622] 'applied index is now lower than readState.Index' (duration: 22.051764ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:41:16.219572Z","caller":"traceutil/trace.go:171","msg":"trace[1106974395] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"265.763876ms","start":"2026-04-07T01:41:15.953784Z","end":"2026-04-07T01:41:16.219548Z","steps":["trace[1106974395] 'process raft request' (duration: 265.385738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:16.219677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.676456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T01:41:16.219757Z","caller":"traceutil/trace.go:171","msg":"trace[180773129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8550; }","duration":"255.781638ms","start":"2026-04-07T01:41:15.963958Z","end":"2026-04-07T01:41:16.21974Z","steps":["trace[180773129] 'agreement among raft nodes before linearized reading' (duration: 255.543612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:16.219778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.414566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T01:41:16.219799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.185348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zxtzm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T01:41:16.219845Z","caller":"traceutil/trace.go:171","msg":"trace[630392587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8550; }","duration":"264.520968ms","start":"2026-04-07T01:41:15.955307Z","end":"2026-04-07T01:41:16.219828Z","steps":["trace[630392587] 'agreement among raft nodes before linearized reading' (duration: 264.331403ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:16.219851Z","caller":"traceutil/trace.go:171","msg":"trace[2040152069] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zxtzm; range_end:; response_count:1; response_revision:8550; }","duration":"246.27007ms","start":"2026-04-07T01:41:15.973568Z","end":"2026-04-07T01:41:16.219838Z","steps":["trace[2040152069] 'agreement among raft nodes before linearized reading' (duration: 246.067925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:16.219867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.524669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T01:41:16.219913Z","caller":"traceutil/trace.go:171","msg":"trace[532595989] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8550; }","duration":"254.60484ms","start":"2026-04-07T01:41:15.965294Z","end":"2026-04-07T01:41:16.219899Z","steps":["trace[532595989] 'agreement among raft nodes before linearized reading' (duration: 254.473297ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:16.219882Z","caller":"traceutil/trace.go:171","msg":"trace[379149624] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"267.22335ms","start":"2026-04-07T01:41:15.952632Z","end":"2026-04-07T01:41:16.219855Z","steps":["trace[379149624] 'process raft request' (duration: 244.559982ms)","trace[379149624] 'compare' (duration: 21.829719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:41:16.22007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.69441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T01:41:16.220154Z","caller":"traceutil/trace.go:171","msg":"trace[168058408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8550; }","duration":"253.811483ms","start":"2026-04-07T01:41:15.966324Z","end":"2026-04-07T01:41:16.220136Z","steps":["trace[168058408] 'agreement among raft nodes before linearized reading' (duration: 253.549027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:16.220237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.723293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-07T01:41:16.220437Z","caller":"traceutil/trace.go:171","msg":"trace[1995176221] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8550; }","duration":"255.046461ms","start":"2026-04-07T01:41:15.965368Z","end":"2026-04-07T01:41:16.220415Z","steps":["trace[1995176221] 'agreement among raft nodes before linearized reading' (duration: 254.344345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:16.926699Z","caller":"traceutil/trace.go:171","msg":"trace[676224002] linearizableReadLoop","detail":"{readStateIndex:8864; appliedIndex:8863; }","duration":"116.623811ms","start":"2026-04-07T01:41:16.810052Z","end":"2026-04-07T01:41:16.926675Z","steps":["trace[676224002] 'read index received' (duration: 116.482858ms)","trace[676224002] 'applied index is now lower than readState.Index' (duration: 140.363µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:41:16.926845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.779325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T01:41:16.926875Z","caller":"traceutil/trace.go:171","msg":"trace[1264534025] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8561; }","duration":"116.842455ms","start":"2026-04-07T01:41:16.810023Z","end":"2026-04-07T01:41:16.926866Z","steps":["trace[1264534025] 'agreement among raft nodes before linearized reading' (duration: 116.727703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:16.927136Z","caller":"traceutil/trace.go:171","msg":"trace[1716784221] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"182.53081ms","start":"2026-04-07T01:41:16.744597Z","end":"2026-04-07T01:41:16.927128Z","steps":["trace[1716784221] 'process raft request' (duration: 181.978698ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:41:17.149769Z","caller":"traceutil/trace.go:171","msg":"trace[1426730255] linearizableReadLoop","detail":"{readStateIndex:8865; appliedIndex:8864; }","duration":"161.12675ms","start":"2026-04-07T01:41:16.98859Z","end":"2026-04-07T01:41:17.149717Z","steps":["trace[1426730255] 'read index received' (duration: 111.556855ms)","trace[1426730255] 'applied index is now lower than readState.Index' (duration: 49.563405ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:41:17.149848Z","caller":"traceutil/trace.go:171","msg":"trace[65959503] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"199.749114ms","start":"2026-04-07T01:41:16.95006Z","end":"2026-04-07T01:41:17.149809Z","steps":["trace[65959503] 'process raft request' (duration: 150.146418ms)","trace[65959503] 'compare' (duration: 49.194417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:41:17.150001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.122388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:41:17.150085Z","caller":"traceutil/trace.go:171","msg":"trace[884554914] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8562; }","duration":"157.21801ms","start":"2026-04-07T01:41:16.992851Z","end":"2026-04-07T01:41:17.150069Z","steps":["trace[884554914] 'agreement among raft nodes before linearized reading' (duration: 157.093167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:41:17.150297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.694593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-07T01:41:17.150362Z","caller":"traceutil/trace.go:171","msg":"trace[633984400] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8562; }","duration":"161.763634ms","start":"2026-04-07T01:41:16.988583Z","end":"2026-04-07T01:41:17.150347Z","steps":["trace[633984400] 'agreement among raft nodes before linearized reading' (duration: 161.312394ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:42:08.207384Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b3.wal"} {"level":"info","ts":"2026-04-07T01:42:08.208173Z","caller":"traceutil/trace.go:171","msg":"trace[622307458] linearizableReadLoop","detail":"{readStateIndex:9394; appliedIndex:9393; }","duration":"102.425456ms","start":"2026-04-07T01:42:08.105716Z","end":"2026-04-07T01:42:08.208142Z","steps":["trace[622307458] 'read index received' (duration: 102.008236ms)","trace[622307458] 'applied index is now lower than readState.Index' (duration: 416.19µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:42:08.208223Z","caller":"traceutil/trace.go:171","msg":"trace[276079829] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"108.770231ms","start":"2026-04-07T01:42:08.099413Z","end":"2026-04-07T01:42:08.208183Z","steps":["trace[276079829] 'process raft request' (duration: 108.338291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:42:08.20834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.607959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:42:08.208405Z","caller":"traceutil/trace.go:171","msg":"trace[675574780] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9078; }","duration":"102.730703ms","start":"2026-04-07T01:42:08.105653Z","end":"2026-04-07T01:42:08.208383Z","steps":["trace[675574780] 'agreement among raft nodes before linearized reading' (duration: 102.618931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:43:20.992441Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:43:20.998018Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T01:43:20.998127Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T01:44:52.453355Z","caller":"traceutil/trace.go:171","msg":"trace[2097500293] transaction","detail":"{read_only:false; response_revision:10360; number_of_response:1; }","duration":"112.446063ms","start":"2026-04-07T01:44:52.340887Z","end":"2026-04-07T01:44:52.453333Z","steps":["trace[2097500293] 'process raft request' (duration: 112.32493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:45:46.441455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8307} {"level":"info","ts":"2026-04-07T01:45:46.529469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8307,"took":"85.662357ms","hash":1354473347,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":24854528,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T01:45:46.529568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1354473347,"revision":8307,"compact-revision":6134} {"level":"info","ts":"2026-04-07T01:48:02.694913Z","caller":"traceutil/trace.go:171","msg":"trace[1724901034] transaction","detail":"{read_only:false; response_revision:11406; number_of_response:1; }","duration":"305.6251ms","start":"2026-04-07T01:48:02.389233Z","end":"2026-04-07T01:48:02.694858Z","steps":["trace[1724901034] 'process raft request' (duration: 304.7806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:48:02.695122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:48:02.389221Z","time spent":"305.798744ms","remote":"127.0.0.1:39308","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-04-07T01:50:46.448361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10694} {"level":"info","ts":"2026-04-07T01:50:46.530932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10694,"took":"79.680406ms","hash":2956614834,"current-db-size-bytes":58048512,"current-db-size":"58 MB","current-db-size-in-use-bytes":20459520,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T01:50:46.531017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2956614834,"revision":10694,"compact-revision":8307}