{"level":"warn","ts":"2026-07-01T07:25:36.14151Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:25:36.141627Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T07:25:36.141739Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:25:36.141763Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-01T07:25:36.141787Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:25:36.142862Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-07-01T07:25:36.142974Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T07:25:36.147127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.882502ms"} {"level":"info","ts":"2026-07-01T07:25:36.152465Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-07-01T07:25:36.15257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:25:36.152601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-07-01T07:25:36.152621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:25:36.152634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-07-01T07:25:36.152673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-07-01T07:25:36.15846Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:25:36.160414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:25:36.161738Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T07:25:36.164129Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:25:36.164268Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:25:36.164284Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:25:36.164382Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:25:36.164411Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:25:36.165315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-07-01T07:25:36.167475Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-01T07:25:36.170021Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:25:36.170237Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:25:36.170306Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:25:36.170727Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-01T07:25:36.170767Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-01T07:25:36.65343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:25:36.653501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:25:36.653593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-07-01T07:25:36.653632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:25:36.653679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-01T07:25:36.653728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-07-01T07:25:36.653749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-01T07:25:36.659212Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:36.668612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:25:36.668604Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:25:36.668658Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:25:36.668942Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:25:36.669013Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:25:36.670656Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:36.670757Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:36.670792Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:36.672626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:25:36.673963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-07-01T07:26:17.898286Z","caller":"traceutil/trace.go:171","msg":"trace[1705087630] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"108.403533ms","start":"2026-07-01T07:26:17.789842Z","end":"2026-07-01T07:26:17.898246Z","steps":["trace[1705087630] 'process raft request' (duration: 108.198837ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:18.286836Z","caller":"traceutil/trace.go:171","msg":"trace[1158242536] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"198.830141ms","start":"2026-07-01T07:26:18.087969Z","end":"2026-07-01T07:26:18.286799Z","steps":["trace[1158242536] 'read index received' (duration: 198.07269ms)","trace[1158242536] 'applied index is now lower than readState.Index' (duration: 756.141µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:18.286909Z","caller":"traceutil/trace.go:171","msg":"trace[521908269] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"263.248156ms","start":"2026-07-01T07:26:18.023614Z","end":"2026-07-01T07:26:18.286862Z","steps":["trace[521908269] 'process raft request' (duration: 262.61477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:18.287024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.987245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:26:18.287089Z","caller":"traceutil/trace.go:171","msg":"trace[1820743969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"199.117869ms","start":"2026-07-01T07:26:18.087958Z","end":"2026-07-01T07:26:18.287076Z","steps":["trace[1820743969] 'agreement among raft nodes before linearized reading' (duration: 198.963835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:18.774455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.894078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:26:18.774566Z","caller":"traceutil/trace.go:171","msg":"trace[895920171] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"428.125954ms","start":"2026-07-01T07:26:18.346422Z","end":"2026-07-01T07:26:18.774548Z","steps":["trace[895920171] 'read index received' (duration: 251.486577ms)","trace[895920171] 'applied index is now lower than readState.Index' (duration: 176.638197ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:18.774616Z","caller":"traceutil/trace.go:171","msg":"trace[491326611] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"445.11991ms","start":"2026-07-01T07:26:18.329463Z","end":"2026-07-01T07:26:18.774583Z","steps":["trace[491326611] 'process raft request' (duration: 268.439302ms)","trace[491326611] 'compare' (duration: 175.61613ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:18.77471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.284078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T07:26:18.77475Z","caller":"traceutil/trace.go:171","msg":"trace[1449560767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"428.35433ms","start":"2026-07-01T07:26:18.346385Z","end":"2026-07-01T07:26:18.774739Z","steps":["trace[1449560767] 'agreement among raft nodes before linearized reading' (duration: 428.210156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:18.774801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:18.346365Z","time spent":"428.419211ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:26:18.774767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:18.329285Z","time spent":"445.398938ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:26:18.776967Z","caller":"traceutil/trace.go:171","msg":"trace[1586873677] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"425.000032ms","start":"2026-07-01T07:26:18.351938Z","end":"2026-07-01T07:26:18.776938Z","steps":["trace[1586873677] 'process raft request' (duration: 424.882348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:18.777102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:18.351913Z","time spent":"425.125535ms","remote":"127.0.0.1:41302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:27:00.652015Z","caller":"traceutil/trace.go:171","msg":"trace[1001421161] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"109.825177ms","start":"2026-07-01T07:27:00.542158Z","end":"2026-07-01T07:27:00.651983Z","steps":["trace[1001421161] 'process raft request' (duration: 109.654383ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:43.084421Z","caller":"traceutil/trace.go:171","msg":"trace[1689083692] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1856; }","duration":"220.71162ms","start":"2026-07-01T07:27:42.863678Z","end":"2026-07-01T07:27:43.08439Z","steps":["trace[1689083692] 'read index received' (duration: 220.32702ms)","trace[1689083692] 'applied index is now lower than readState.Index' (duration: 383.13µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:43.084591Z","caller":"traceutil/trace.go:171","msg":"trace[1098109711] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"241.635393ms","start":"2026-07-01T07:27:42.842784Z","end":"2026-07-01T07:27:43.084419Z","steps":["trace[1098109711] 'process raft request' (duration: 241.402227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:43.084695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.998017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T07:27:43.084748Z","caller":"traceutil/trace.go:171","msg":"trace[434174910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1787; }","duration":"221.12147ms","start":"2026-07-01T07:27:42.863615Z","end":"2026-07-01T07:27:43.084736Z","steps":["trace[434174910] 'agreement among raft nodes before linearized reading' (duration: 220.918945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:33.789471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.311039ms","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-07-01T07:28:33.789598Z","caller":"traceutil/trace.go:171","msg":"trace[1339615498] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"171.457943ms","start":"2026-07-01T07:28:33.618105Z","end":"2026-07-01T07:28:33.789563Z","steps":["trace[1339615498] 'range keys from in-memory index tree' (duration: 171.064752ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:23.030169Z","caller":"traceutil/trace.go:171","msg":"trace[974016667] linearizableReadLoop","detail":"{readStateIndex:2654; appliedIndex:2653; }","duration":"106.481234ms","start":"2026-07-01T07:29:22.923658Z","end":"2026-07-01T07:29:23.030139Z","steps":["trace[974016667] 'read index received' (duration: 106.346391ms)","trace[974016667] 'applied index is now lower than readState.Index' (duration: 133.693µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:23.030209Z","caller":"traceutil/trace.go:171","msg":"trace[1909622732] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"129.616196ms","start":"2026-07-01T07:29:22.90056Z","end":"2026-07-01T07:29:23.030176Z","steps":["trace[1909622732] 'process raft request' (duration: 129.36246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:23.030542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.853423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T07:29:23.03082Z","caller":"traceutil/trace.go:171","msg":"trace[1273491913] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2556; }","duration":"107.054407ms","start":"2026-07-01T07:29:22.923599Z","end":"2026-07-01T07:29:23.030653Z","steps":["trace[1273491913] 'agreement among raft nodes before linearized reading' (duration: 106.669968ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:23.07316Z","caller":"traceutil/trace.go:171","msg":"trace[454294434] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"110.962854ms","start":"2026-07-01T07:29:22.962165Z","end":"2026-07-01T07:29:23.073128Z","steps":["trace[454294434] 'process raft request' (duration: 102.876787ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:23.073326Z","caller":"traceutil/trace.go:171","msg":"trace[161449616] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"103.204055ms","start":"2026-07-01T07:29:22.970097Z","end":"2026-07-01T07:29:23.073302Z","steps":["trace[161449616] 'process raft request' (duration: 102.99202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:23.073548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.94331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T07:29:23.073574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.202232ms","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-07-01T07:29:23.073601Z","caller":"traceutil/trace.go:171","msg":"trace[1941701124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2559; }","duration":"143.046262ms","start":"2026-07-01T07:29:22.930542Z","end":"2026-07-01T07:29:23.073588Z","steps":["trace[1941701124] 'agreement among raft nodes before linearized reading' (duration: 142.843277ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:23.073609Z","caller":"traceutil/trace.go:171","msg":"trace[1749908182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2559; }","duration":"100.261794ms","start":"2026-07-01T07:29:22.973336Z","end":"2026-07-01T07:29:23.073598Z","steps":["trace[1749908182] 'agreement among raft nodes before linearized reading' (duration: 100.137331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:23.073641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.265444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-01T07:29:23.073697Z","caller":"traceutil/trace.go:171","msg":"trace[63017617] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2559; }","duration":"140.354918ms","start":"2026-07-01T07:29:22.933325Z","end":"2026-07-01T07:29:23.07368Z","steps":["trace[63017617] 'agreement among raft nodes before linearized reading' (duration: 140.164212ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:33:49.191871Z","caller":"traceutil/trace.go:171","msg":"trace[312333026] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"139.464336ms","start":"2026-07-01T07:33:49.052377Z","end":"2026-07-01T07:33:49.191841Z","steps":["trace[312333026] 'process raft request' (duration: 138.937644ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:33:58.391673Z","caller":"traceutil/trace.go:171","msg":"trace[295169420] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"118.1295ms","start":"2026-07-01T07:33:58.273509Z","end":"2026-07-01T07:33:58.391639Z","steps":["trace[295169420] 'process raft request' (duration: 102.309464ms)","trace[295169420] 'compare' (duration: 15.539849ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:33:58.391731Z","caller":"traceutil/trace.go:171","msg":"trace[318168940] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"118.12711ms","start":"2026-07-01T07:33:58.273555Z","end":"2026-07-01T07:33:58.391683Z","steps":["trace[318168940] 'process raft request' (duration: 117.959016ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:33:58.391816Z","caller":"traceutil/trace.go:171","msg":"trace[1027495244] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"118.175881ms","start":"2026-07-01T07:33:58.273601Z","end":"2026-07-01T07:33:58.391777Z","steps":["trace[1027495244] 'process raft request' (duration: 117.968056ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:02.139844Z","caller":"traceutil/trace.go:171","msg":"trace[552909538] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"139.476221ms","start":"2026-07-01T07:34:02.000348Z","end":"2026-07-01T07:34:02.139824Z","steps":["trace[552909538] 'process raft request' (duration: 139.293207ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:10.611942Z","caller":"traceutil/trace.go:171","msg":"trace[291851966] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"220.301522ms","start":"2026-07-01T07:34:10.391611Z","end":"2026-07-01T07:34:10.611913Z","steps":["trace[291851966] 'read index received' (duration: 220.147959ms)","trace[291851966] 'applied index is now lower than readState.Index' (duration: 152.343µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:34:10.612161Z","caller":"traceutil/trace.go:171","msg":"trace[1520197763] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"229.983318ms","start":"2026-07-01T07:34:10.38204Z","end":"2026-07-01T07:34:10.612023Z","steps":["trace[1520197763] 'process raft request' (duration: 229.685491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:34:10.612228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.592499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-07-01T07:34:10.612342Z","caller":"traceutil/trace.go:171","msg":"trace[929229162] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5329; }","duration":"220.758073ms","start":"2026-07-01T07:34:10.391554Z","end":"2026-07-01T07:34:10.612312Z","steps":["trace[929229162] 'agreement among raft nodes before linearized reading' (duration: 220.491137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:34:12.631406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.144899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-01T07:34:12.63153Z","caller":"traceutil/trace.go:171","msg":"trace[1614263416] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5376; }","duration":"195.292593ms","start":"2026-07-01T07:34:12.436211Z","end":"2026-07-01T07:34:12.631503Z","steps":["trace[1614263416] 'range keys from in-memory index tree' (duration: 194.898693ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:12.631641Z","caller":"traceutil/trace.go:171","msg":"trace[1483085455] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"190.839173ms","start":"2026-07-01T07:34:12.440766Z","end":"2026-07-01T07:34:12.631605Z","steps":["trace[1483085455] 'process raft request' (duration: 112.947384ms)","trace[1483085455] 'compare' (duration: 77.563281ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:35:36.715845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-07-01T07:35:36.784199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"66.059071ms","hash":22356597,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":34336768,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T07:35:36.784273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":22356597,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-07-01T07:40:07.477043Z","caller":"traceutil/trace.go:171","msg":"trace[1412433503] transaction","detail":"{read_only:false; response_revision:8119; number_of_response:1; }","duration":"195.411549ms","start":"2026-07-01T07:40:07.281603Z","end":"2026-07-01T07:40:07.477015Z","steps":["trace[1412433503] 'process raft request' (duration: 195.255415ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:40:07.522336Z","caller":"traceutil/trace.go:171","msg":"trace[1621198901] linearizableReadLoop","detail":"{readStateIndex:8406; appliedIndex:8403; }","duration":"193.852348ms","start":"2026-07-01T07:40:07.328452Z","end":"2026-07-01T07:40:07.522304Z","steps":["trace[1621198901] 'read index received' (duration: 148.461195ms)","trace[1621198901] 'applied index is now lower than readState.Index' (duration: 45.390033ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:40:07.522776Z","caller":"traceutil/trace.go:171","msg":"trace[1790032448] transaction","detail":"{read_only:false; response_revision:8120; number_of_response:1; }","duration":"239.783285ms","start":"2026-07-01T07:40:07.28295Z","end":"2026-07-01T07:40:07.522733Z","steps":["trace[1790032448] 'process raft request' (duration: 239.152778ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:40:07.522827Z","caller":"traceutil/trace.go:171","msg":"trace[942734146] transaction","detail":"{read_only:false; response_revision:8121; number_of_response:1; }","duration":"239.376724ms","start":"2026-07-01T07:40:07.283416Z","end":"2026-07-01T07:40:07.522793Z","steps":["trace[942734146] 'process raft request' (duration: 238.84638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:07.522856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.157696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-01T07:40:07.523043Z","caller":"traceutil/trace.go:171","msg":"trace[79088470] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8121; }","duration":"194.615788ms","start":"2026-07-01T07:40:07.32841Z","end":"2026-07-01T07:40:07.523025Z","steps":["trace[79088470] 'agreement among raft nodes before linearized reading' (duration: 194.016692ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:40:18.940639Z","caller":"traceutil/trace.go:171","msg":"trace[107407267] linearizableReadLoop","detail":"{readStateIndex:8466; appliedIndex:8465; }","duration":"331.654469ms","start":"2026-07-01T07:40:18.60896Z","end":"2026-07-01T07:40:18.940615Z","steps":["trace[107407267] 'read index received' (duration: 331.499225ms)","trace[107407267] 'applied index is now lower than readState.Index' (duration: 154.004µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:40:18.940712Z","caller":"traceutil/trace.go:171","msg":"trace[2123545624] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"345.218589ms","start":"2026-07-01T07:40:18.595442Z","end":"2026-07-01T07:40:18.940661Z","steps":["trace[2123545624] 'process raft request' (duration: 345.021134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.940829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.491675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:40:18.940826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.841994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:40:18.940881Z","caller":"traceutil/trace.go:171","msg":"trace[1305508697] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8179; }","duration":"331.575997ms","start":"2026-07-01T07:40:18.609289Z","end":"2026-07-01T07:40:18.940865Z","steps":["trace[1305508697] 'agreement among raft nodes before linearized reading' (duration: 331.481894ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:40:18.94089Z","caller":"traceutil/trace.go:171","msg":"trace[543834062] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8179; }","duration":"331.956227ms","start":"2026-07-01T07:40:18.60892Z","end":"2026-07-01T07:40:18.940877Z","steps":["trace[543834062] 'agreement among raft nodes before linearized reading' (duration: 331.801423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.940927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.608909Z","time spent":"332.012198ms","remote":"127.0.0.1:52430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":26,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 "} {"level":"warn","ts":"2026-07-01T07:40:18.940952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.282645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:40:18.940972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.533363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:40:18.940956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.595426Z","time spent":"345.407054ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:40:18.941031Z","caller":"traceutil/trace.go:171","msg":"trace[1237683779] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8179; }","duration":"291.354048ms","start":"2026-07-01T07:40:18.649633Z","end":"2026-07-01T07:40:18.940987Z","steps":["trace[1237683779] 'agreement among raft nodes before linearized reading' (duration: 291.277645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.940863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.371228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:40:18.941101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.875331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:40:18.940924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.60928Z","time spent":"331.634409ms","remote":"127.0.0.1:52430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:40:18.941142Z","caller":"traceutil/trace.go:171","msg":"trace[1058183748] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8179; }","duration":"308.972683ms","start":"2026-07-01T07:40:18.632157Z","end":"2026-07-01T07:40:18.94113Z","steps":["trace[1058183748] 'agreement among raft nodes before linearized reading' (duration: 308.921852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.941171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.632131Z","time spent":"309.032925ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":26,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:40:18.941139Z","caller":"traceutil/trace.go:171","msg":"trace[1588226362] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8179; }","duration":"291.678628ms","start":"2026-07-01T07:40:18.649416Z","end":"2026-07-01T07:40:18.941094Z","steps":["trace[1588226362] 'agreement among raft nodes before linearized reading' (duration: 291.374979ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:40:18.94106Z","caller":"traceutil/trace.go:171","msg":"trace[639700577] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8179; }","duration":"291.627156ms","start":"2026-07-01T07:40:18.649413Z","end":"2026-07-01T07:40:18.94104Z","steps":["trace[639700577] 'agreement among raft nodes before linearized reading' (duration: 291.532683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.941243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.322408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-57hjt\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-07-01T07:40:18.94131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.253491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:40:18.94133Z","caller":"traceutil/trace.go:171","msg":"trace[2116841517] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8179; }","duration":"309.315872ms","start":"2026-07-01T07:40:18.632007Z","end":"2026-07-01T07:40:18.941323Z","steps":["trace[2116841517] 'agreement among raft nodes before linearized reading' (duration: 309.283092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.941344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.631972Z","time spent":"309.367194ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":26,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 "} {"level":"info","ts":"2026-07-01T07:40:18.941356Z","caller":"traceutil/trace.go:171","msg":"trace[1633099394] range","detail":"{range_begin:/registry/pods/openstack/keepalived-57hjt; range_end:; response_count:1; response_revision:8179; }","duration":"262.481792ms","start":"2026-07-01T07:40:18.678841Z","end":"2026-07-01T07:40:18.941323Z","steps":["trace[1633099394] 'agreement among raft nodes before linearized reading' (duration: 262.063481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.94135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.055852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:40:18.941454Z","caller":"traceutil/trace.go:171","msg":"trace[52311240] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8179; }","duration":"310.200005ms","start":"2026-07-01T07:40:18.631227Z","end":"2026-07-01T07:40:18.941427Z","steps":["trace[52311240] 'agreement among raft nodes before linearized reading' (duration: 309.967719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.941462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.213649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:40:18.941507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.631214Z","time spent":"310.281517ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":26,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 "} {"level":"info","ts":"2026-07-01T07:40:18.941545Z","caller":"traceutil/trace.go:171","msg":"trace[408300621] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8179; }","duration":"331.363292ms","start":"2026-07-01T07:40:18.61016Z","end":"2026-07-01T07:40:18.941523Z","steps":["trace[408300621] 'agreement among raft nodes before linearized reading' (duration: 331.167757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.941552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.198987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-01T07:40:18.941583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.61014Z","time spent":"331.430525ms","remote":"127.0.0.1:52430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":26,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 "} {"level":"info","ts":"2026-07-01T07:40:18.941609Z","caller":"traceutil/trace.go:171","msg":"trace[190937150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8179; }","duration":"317.262188ms","start":"2026-07-01T07:40:18.624331Z","end":"2026-07-01T07:40:18.941594Z","steps":["trace[190937150] 'agreement among raft nodes before linearized reading' (duration: 316.966331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:18.941647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:40:18.624303Z","time spent":"317.33874ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T07:40:19.740316Z","caller":"traceutil/trace.go:171","msg":"trace[1766990316] linearizableReadLoop","detail":"{readStateIndex:8469; appliedIndex:8468; }","duration":"159.325706ms","start":"2026-07-01T07:40:19.580948Z","end":"2026-07-01T07:40:19.740273Z","steps":["trace[1766990316] 'read index received' (duration: 159.09223ms)","trace[1766990316] 'applied index is now lower than readState.Index' (duration: 231.606µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:40:19.74041Z","caller":"traceutil/trace.go:171","msg":"trace[1169139905] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"198.771025ms","start":"2026-07-01T07:40:19.541606Z","end":"2026-07-01T07:40:19.740377Z","steps":["trace[1169139905] 'process raft request' (duration: 198.402956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:19.740563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.610353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:40:19.740624Z","caller":"traceutil/trace.go:171","msg":"trace[1614259655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8182; }","duration":"159.727376ms","start":"2026-07-01T07:40:19.580884Z","end":"2026-07-01T07:40:19.740612Z","steps":["trace[1614259655] 'agreement among raft nodes before linearized reading' (duration: 159.566502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:19.774335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.368573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T07:40:19.774408Z","caller":"traceutil/trace.go:171","msg":"trace[1602231336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8185; }","duration":"189.468746ms","start":"2026-07-01T07:40:19.584922Z","end":"2026-07-01T07:40:19.774391Z","steps":["trace[1602231336] 'agreement among raft nodes before linearized reading' (duration: 189.23463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:40:19.774572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.065715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-01T07:40:19.774603Z","caller":"traceutil/trace.go:171","msg":"trace[1366098551] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8185; }","duration":"124.121467ms","start":"2026-07-01T07:40:19.650467Z","end":"2026-07-01T07:40:19.774589Z","steps":["trace[1366098551] 'agreement among raft nodes before linearized reading' (duration: 124.043165ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:40:36.722856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6111} {"level":"info","ts":"2026-07-01T07:40:36.819173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6111,"took":"94.347157ms","hash":4178768910,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":23052288,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-01T07:40:36.819263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4178768910,"revision":6111,"compact-revision":2980} {"level":"info","ts":"2026-07-01T07:41:01.446399Z","caller":"traceutil/trace.go:171","msg":"trace[557486061] linearizableReadLoop","detail":"{readStateIndex:8809; appliedIndex:8808; }","duration":"148.318282ms","start":"2026-07-01T07:41:01.298048Z","end":"2026-07-01T07:41:01.446366Z","steps":["trace[557486061] 'read index received' (duration: 121.728715ms)","trace[557486061] 'applied index is now lower than readState.Index' (duration: 26.587677ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:41:01.446454Z","caller":"traceutil/trace.go:171","msg":"trace[669878700] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8506; }","duration":"176.310615ms","start":"2026-07-01T07:41:01.270096Z","end":"2026-07-01T07:41:01.446407Z","steps":["trace[669878700] 'process raft request' (duration: 149.649146ms)","trace[669878700] 'compare' (duration: 26.388391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:41:01.446638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.577659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2zf7m\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-07-01T07:41:01.446747Z","caller":"traceutil/trace.go:171","msg":"trace[1113814533] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2zf7m; range_end:; response_count:1; response_revision:8506; }","duration":"148.718742ms","start":"2026-07-01T07:41:01.29797Z","end":"2026-07-01T07:41:01.446689Z","steps":["trace[1113814533] 'agreement among raft nodes before linearized reading' (duration: 148.466956ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:02.029514Z","caller":"traceutil/trace.go:171","msg":"trace[413660730] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"104.448529ms","start":"2026-07-01T07:41:01.925038Z","end":"2026-07-01T07:41:02.029487Z","steps":["trace[413660730] 'process raft request' (duration: 104.317846ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:02.098998Z","caller":"traceutil/trace.go:171","msg":"trace[491406703] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"172.948348ms","start":"2026-07-01T07:41:01.92602Z","end":"2026-07-01T07:41:02.098969Z","steps":["trace[491406703] 'process raft request' (duration: 149.622195ms)","trace[491406703] 'compare' (duration: 22.958494ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:41:02.099093Z","caller":"traceutil/trace.go:171","msg":"trace[412965776] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"170.807324ms","start":"2026-07-01T07:41:01.928263Z","end":"2026-07-01T07:41:02.09907Z","steps":["trace[412965776] 'process raft request' (duration: 170.610498ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:02.09939Z","caller":"traceutil/trace.go:171","msg":"trace[1918043358] linearizableReadLoop","detail":"{readStateIndex:8812; appliedIndex:8810; }","duration":"148.834877ms","start":"2026-07-01T07:41:01.950513Z","end":"2026-07-01T07:41:02.099348Z","steps":["trace[1918043358] 'read index received' (duration: 79.010152ms)","trace[1918043358] 'applied index is now lower than readState.Index' (duration: 69.822885ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:41:02.099612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.074243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:41:02.099686Z","caller":"traceutil/trace.go:171","msg":"trace[266860634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8510; }","duration":"149.196186ms","start":"2026-07-01T07:41:01.950471Z","end":"2026-07-01T07:41:02.099667Z","steps":["trace[266860634] 'agreement among raft nodes before linearized reading' (duration: 148.991731ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:02.218939Z","caller":"traceutil/trace.go:171","msg":"trace[230353508] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"112.863526ms","start":"2026-07-01T07:41:02.106047Z","end":"2026-07-01T07:41:02.21891Z","steps":["trace[230353508] 'process raft request' (duration: 112.747023ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:02.219048Z","caller":"traceutil/trace.go:171","msg":"trace[331883668] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"111.522591ms","start":"2026-07-01T07:41:02.107504Z","end":"2026-07-01T07:41:02.219026Z","steps":["trace[331883668] 'process raft request' (duration: 111.355557ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:02.218939Z","caller":"traceutil/trace.go:171","msg":"trace[586186091] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"113.237506ms","start":"2026-07-01T07:41:02.105669Z","end":"2026-07-01T07:41:02.218907Z","steps":["trace[586186091] 'process raft request' (duration: 99.334647ms)","trace[586186091] 'compare' (duration: 13.612971ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:41:03.1432Z","caller":"traceutil/trace.go:171","msg":"trace[1983994084] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"126.447106ms","start":"2026-07-01T07:41:03.016721Z","end":"2026-07-01T07:41:03.143168Z","steps":["trace[1983994084] 'process raft request' (duration: 126.260351ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:41:27.394605Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002392.wal"} {"level":"info","ts":"2026-07-01T07:43:08.908624Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T07:43:08.914564Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T07:43:08.914731Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T07:45:36.72987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8282} {"level":"info","ts":"2026-07-01T07:45:36.803664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8282,"took":"71.104867ms","hash":4085347491,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":26869760,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-01T07:45:36.803745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4085347491,"revision":8282,"compact-revision":6111} {"level":"info","ts":"2026-07-01T07:50:36.736645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10995} {"level":"info","ts":"2026-07-01T07:50:36.821453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10995,"took":"80.839356ms","hash":1438131315,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":28393472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-01T07:50:36.821519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1438131315,"revision":10995,"compact-revision":8282} {"level":"info","ts":"2026-07-01T07:51:44.899698Z","caller":"traceutil/trace.go:171","msg":"trace[131668711] transaction","detail":"{read_only:false; response_revision:13489; number_of_response:1; }","duration":"109.391104ms","start":"2026-07-01T07:51:44.790272Z","end":"2026-07-01T07:51:44.899663Z","steps":["trace[131668711] 'process raft request' (duration: 109.187409ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:52:20.613917Z","caller":"traceutil/trace.go:171","msg":"trace[1173905841] transaction","detail":"{read_only:false; response_revision:13691; number_of_response:1; }","duration":"367.999636ms","start":"2026-07-01T07:52:20.24588Z","end":"2026-07-01T07:52:20.61388Z","steps":["trace[1173905841] 'process raft request' (duration: 367.439823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:52:20.614185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:52:20.245858Z","time spent":"368.227051ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:52:30.62737Z","caller":"traceutil/trace.go:171","msg":"trace[794231232] transaction","detail":"{read_only:false; response_revision:13861; number_of_response:1; }","duration":"132.243891ms","start":"2026-07-01T07:52:30.495084Z","end":"2026-07-01T07:52:30.627328Z","steps":["trace[794231232] 'process raft request' (duration: 102.830591ms)","trace[794231232] 'compare' (duration: 29.234875ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:52:30.898946Z","caller":"traceutil/trace.go:171","msg":"trace[1058459464] linearizableReadLoop","detail":"{readStateIndex:14336; appliedIndex:14335; }","duration":"156.990291ms","start":"2026-07-01T07:52:30.741921Z","end":"2026-07-01T07:52:30.898911Z","steps":["trace[1058459464] 'read index received' (duration: 79.166726ms)","trace[1058459464] 'applied index is now lower than readState.Index' (duration: 77.820975ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:52:30.89897Z","caller":"traceutil/trace.go:171","msg":"trace[389492367] transaction","detail":"{read_only:false; response_revision:13862; number_of_response:1; }","duration":"160.80356ms","start":"2026-07-01T07:52:30.738079Z","end":"2026-07-01T07:52:30.898882Z","steps":["trace[389492367] 'process raft request' (duration: 83.072218ms)","trace[389492367] 'compare' (duration: 77.479215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:52:30.899186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.226947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:52:30.899307Z","caller":"traceutil/trace.go:171","msg":"trace[801293184] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:13862; }","duration":"157.417741ms","start":"2026-07-01T07:52:30.74186Z","end":"2026-07-01T07:52:30.899278Z","steps":["trace[801293184] 'agreement among raft nodes before linearized reading' (duration: 157.198156ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:52:30.955247Z","caller":"traceutil/trace.go:171","msg":"trace[1357843523] transaction","detail":"{read_only:false; response_revision:13863; number_of_response:1; }","duration":"101.029978ms","start":"2026-07-01T07:52:30.854184Z","end":"2026-07-01T07:52:30.955214Z","steps":["trace[1357843523] 'process raft request' (duration: 100.868115ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:54:00.585668Z","caller":"traceutil/trace.go:171","msg":"trace[56359317] transaction","detail":"{read_only:false; response_revision:14899; number_of_response:1; }","duration":"121.240823ms","start":"2026-07-01T07:54:00.464392Z","end":"2026-07-01T07:54:00.585633Z","steps":["trace[56359317] 'process raft request' (duration: 120.969937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:54:00.58636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.525227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-01T07:54:00.586477Z","caller":"traceutil/trace.go:171","msg":"trace[1529515337] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14899; }","duration":"100.691801ms","start":"2026-07-01T07:54:00.485756Z","end":"2026-07-01T07:54:00.586448Z","steps":["trace[1529515337] 'agreement among raft nodes before linearized reading' (duration: 100.484956ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:54:01.613811Z","caller":"traceutil/trace.go:171","msg":"trace[884961410] transaction","detail":"{read_only:false; response_revision:14904; number_of_response:1; }","duration":"305.553852ms","start":"2026-07-01T07:54:01.30822Z","end":"2026-07-01T07:54:01.613774Z","steps":["trace[884961410] 'process raft request' (duration: 299.45951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:54:01.614011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:54:01.308197Z","time spent":"305.702246ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:54:01.614204Z","caller":"traceutil/trace.go:171","msg":"trace[210289186] transaction","detail":"{read_only:false; response_revision:14905; number_of_response:1; }","duration":"304.566539ms","start":"2026-07-01T07:54:01.309598Z","end":"2026-07-01T07:54:01.614165Z","steps":["trace[210289186] 'process raft request' (duration: 304.120989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:54:01.614416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:54:01.309562Z","time spent":"304.737803ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:55:36.744968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13034} {"level":"info","ts":"2026-07-01T07:55:36.82095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13034,"took":"73.248553ms","hash":1618422543,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":31436800,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T07:55:36.821028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1618422543,"revision":13034,"compact-revision":10995} {"level":"warn","ts":"2026-07-01T07:59:47.582488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.278949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-01T07:59:47.582585Z","caller":"traceutil/trace.go:171","msg":"trace[222308412] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17480; }","duration":"147.427283ms","start":"2026-07-01T07:59:47.435136Z","end":"2026-07-01T07:59:47.582563Z","steps":["trace[222308412] 'range keys from in-memory index tree' (duration: 147.044593ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:59:53.813617Z","caller":"traceutil/trace.go:171","msg":"trace[279675054] linearizableReadLoop","detail":"{readStateIndex:18107; appliedIndex:18106; }","duration":"268.061965ms","start":"2026-07-01T07:59:53.545523Z","end":"2026-07-01T07:59:53.813585Z","steps":["trace[279675054] 'read index received' (duration: 267.818379ms)","trace[279675054] 'applied index is now lower than readState.Index' (duration: 242.176µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:59:53.813715Z","caller":"traceutil/trace.go:171","msg":"trace[959193822] transaction","detail":"{read_only:false; response_revision:17516; number_of_response:1; }","duration":"358.792659ms","start":"2026-07-01T07:59:53.454891Z","end":"2026-07-01T07:59:53.813684Z","steps":["trace[959193822] 'process raft request' (duration: 358.480722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:59:53.813859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:59:53.454863Z","time spent":"358.914162ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:59:53.813953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.332782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T07:59:53.814002Z","caller":"traceutil/trace.go:171","msg":"trace[1684215856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17516; }","duration":"200.421194ms","start":"2026-07-01T07:59:53.613567Z","end":"2026-07-01T07:59:53.813988Z","steps":["trace[1684215856] 'agreement among raft nodes before linearized reading' (duration: 200.211069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:59:53.814005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.955264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T07:59:53.814091Z","caller":"traceutil/trace.go:171","msg":"trace[638551494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17516; }","duration":"155.106898ms","start":"2026-07-01T07:59:53.65896Z","end":"2026-07-01T07:59:53.814067Z","steps":["trace[638551494] 'agreement among raft nodes before linearized reading' (duration: 154.855302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:59:53.814268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.753881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-01T07:59:53.814296Z","caller":"traceutil/trace.go:171","msg":"trace[1598990806] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17516; }","duration":"268.783162ms","start":"2026-07-01T07:59:53.545506Z","end":"2026-07-01T07:59:53.814289Z","steps":["trace[1598990806] 'agreement among raft nodes before linearized reading' (duration: 268.255419ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:59:53.969287Z","caller":"traceutil/trace.go:171","msg":"trace[367743774] linearizableReadLoop","detail":"{readStateIndex:18109; appliedIndex:18108; }","duration":"134.35819ms","start":"2026-07-01T07:59:53.834908Z","end":"2026-07-01T07:59:53.969266Z","steps":["trace[367743774] 'read index received' (duration: 100.709644ms)","trace[367743774] 'applied index is now lower than readState.Index' (duration: 33.647636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:59:53.969565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.636717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18826"} {"level":"info","ts":"2026-07-01T07:59:53.969631Z","caller":"traceutil/trace.go:171","msg":"trace[848832716] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17518; }","duration":"134.75216ms","start":"2026-07-01T07:59:53.834862Z","end":"2026-07-01T07:59:53.969614Z","steps":["trace[848832716] 'agreement among raft nodes before linearized reading' (duration: 134.518204ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:59:53.969867Z","caller":"traceutil/trace.go:171","msg":"trace[957557356] transaction","detail":"{read_only:false; response_revision:17518; number_of_response:1; }","duration":"147.837222ms","start":"2026-07-01T07:59:53.822012Z","end":"2026-07-01T07:59:53.969849Z","steps":["trace[957557356] 'process raft request' (duration: 113.52872ms)","trace[957557356] 'compare' (duration: 33.631726ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:00:36.751449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15523} {"level":"info","ts":"2026-07-01T08:00:36.848406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15523,"took":"92.6498ms","hash":1446749643,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":33288192,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-01T08:00:36.848466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1446749643,"revision":15523,"compact-revision":13034} {"level":"info","ts":"2026-07-01T08:03:29.865751Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T08:03:29.872544Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T08:03:29.872687Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-01T08:05:01.700581Z","caller":"traceutil/trace.go:171","msg":"trace[1263545697] transaction","detail":"{read_only:false; response_revision:19965; number_of_response:1; }","duration":"132.098046ms","start":"2026-07-01T08:05:01.568441Z","end":"2026-07-01T08:05:01.700539Z","steps":["trace[1263545697] 'process raft request' (duration: 131.85246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:05:01.857651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.83346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T08:05:01.857796Z","caller":"traceutil/trace.go:171","msg":"trace[1813340930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19965; }","duration":"132.041765ms","start":"2026-07-01T08:05:01.725719Z","end":"2026-07-01T08:05:01.857761Z","steps":["trace[1813340930] 'range keys from in-memory index tree' (duration: 131.583663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:01.985478Z","caller":"traceutil/trace.go:171","msg":"trace[1788882871] transaction","detail":"{read_only:false; response_revision:19966; number_of_response:1; }","duration":"121.217245ms","start":"2026-07-01T08:05:01.86421Z","end":"2026-07-01T08:05:01.985427Z","steps":["trace[1788882871] 'process raft request' (duration: 121.061182ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.314998Z","caller":"traceutil/trace.go:171","msg":"trace[381500875] linearizableReadLoop","detail":"{readStateIndex:20638; appliedIndex:20637; }","duration":"227.289759ms","start":"2026-07-01T08:05:02.08768Z","end":"2026-07-01T08:05:02.31497Z","steps":["trace[381500875] 'read index received' (duration: 141.966653ms)","trace[381500875] 'applied index is now lower than readState.Index' (duration: 85.322476ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:05:02.315112Z","caller":"traceutil/trace.go:171","msg":"trace[1405936204] transaction","detail":"{read_only:false; response_revision:19967; number_of_response:1; }","duration":"386.268328ms","start":"2026-07-01T08:05:01.928831Z","end":"2026-07-01T08:05:02.315099Z","steps":["trace[1405936204] 'process raft request' (duration: 300.798719ms)","trace[1405936204] 'compare' (duration: 85.226313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:05:02.315245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:05:01.928801Z","time spent":"386.33856ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:05:02.315587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.845642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:05:02.315714Z","caller":"traceutil/trace.go:171","msg":"trace[569268446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19967; }","duration":"228.064977ms","start":"2026-07-01T08:05:02.087621Z","end":"2026-07-01T08:05:02.315686Z","steps":["trace[569268446] 'agreement among raft nodes before linearized reading' (duration: 227.78859ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.337873Z","caller":"traceutil/trace.go:171","msg":"trace[532264359] transaction","detail":"{read_only:false; response_revision:19969; number_of_response:1; }","duration":"107.675492ms","start":"2026-07-01T08:05:02.230163Z","end":"2026-07-01T08:05:02.337838Z","steps":["trace[532264359] 'process raft request' (duration: 107.409565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:05:02.338306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.557879ms","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-07-01T08:05:02.338273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.555342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:05:02.338589Z","caller":"traceutil/trace.go:171","msg":"trace[1262963212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19970; }","duration":"145.862206ms","start":"2026-07-01T08:05:02.1927Z","end":"2026-07-01T08:05:02.338562Z","steps":["trace[1262963212] 'agreement among raft nodes before linearized reading' (duration: 145.444546ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.338598Z","caller":"traceutil/trace.go:171","msg":"trace[9891209] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:19970; }","duration":"113.994343ms","start":"2026-07-01T08:05:02.224578Z","end":"2026-07-01T08:05:02.338572Z","steps":["trace[9891209] 'agreement among raft nodes before linearized reading' (duration: 113.541542ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.338673Z","caller":"traceutil/trace.go:171","msg":"trace[1533181334] transaction","detail":"{read_only:false; response_revision:19970; number_of_response:1; }","duration":"104.33241ms","start":"2026-07-01T08:05:02.234278Z","end":"2026-07-01T08:05:02.338611Z","steps":["trace[1533181334] 'process raft request' (duration: 103.50704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:05:02.338933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.802918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/cinder-volume-usage-audit-29714885-42vwz\" ","response":"range_response_count:1 size:6752"} {"level":"info","ts":"2026-07-01T08:05:02.338996Z","caller":"traceutil/trace.go:171","msg":"trace[2061163449] range","detail":"{range_begin:/registry/pods/openstack/cinder-volume-usage-audit-29714885-42vwz; range_end:; response_count:1; response_revision:19970; }","duration":"181.90514ms","start":"2026-07-01T08:05:02.157072Z","end":"2026-07-01T08:05:02.338977Z","steps":["trace[2061163449] 'agreement among raft nodes before linearized reading' (duration: 181.674795ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.502796Z","caller":"traceutil/trace.go:171","msg":"trace[1366662422] transaction","detail":"{read_only:false; response_revision:19973; number_of_response:1; }","duration":"157.950156ms","start":"2026-07-01T08:05:02.344808Z","end":"2026-07-01T08:05:02.502758Z","steps":["trace[1366662422] 'process raft request' (duration: 157.795392ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.502801Z","caller":"traceutil/trace.go:171","msg":"trace[911676806] transaction","detail":"{read_only:false; response_revision:19972; number_of_response:1; }","duration":"158.096829ms","start":"2026-07-01T08:05:02.344667Z","end":"2026-07-01T08:05:02.502764Z","steps":["trace[911676806] 'process raft request' (duration: 157.72768ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.502937Z","caller":"traceutil/trace.go:171","msg":"trace[483914290] transaction","detail":"{read_only:false; response_revision:19974; number_of_response:1; }","duration":"153.041948ms","start":"2026-07-01T08:05:02.349877Z","end":"2026-07-01T08:05:02.502919Z","steps":["trace[483914290] 'process raft request' (duration: 152.790552ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.643731Z","caller":"traceutil/trace.go:171","msg":"trace[77307905] transaction","detail":"{read_only:false; response_revision:19975; number_of_response:1; }","duration":"127.100056ms","start":"2026-07-01T08:05:02.516603Z","end":"2026-07-01T08:05:02.643703Z","steps":["trace[77307905] 'process raft request' (duration: 126.941852ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.64977Z","caller":"traceutil/trace.go:171","msg":"trace[1024319511] transaction","detail":"{read_only:false; response_revision:19976; number_of_response:1; }","duration":"112.68352ms","start":"2026-07-01T08:05:02.537048Z","end":"2026-07-01T08:05:02.649731Z","steps":["trace[1024319511] 'process raft request' (duration: 112.521776ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:02.949082Z","caller":"traceutil/trace.go:171","msg":"trace[1075695636] transaction","detail":"{read_only:false; response_revision:19977; number_of_response:1; }","duration":"253.411124ms","start":"2026-07-01T08:05:02.695633Z","end":"2026-07-01T08:05:02.949044Z","steps":["trace[1075695636] 'process raft request' (duration: 253.23484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:05:03.250085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.249954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:05:03.250167Z","caller":"traceutil/trace.go:171","msg":"trace[1292910267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19977; }","duration":"161.380627ms","start":"2026-07-01T08:05:03.088767Z","end":"2026-07-01T08:05:03.250148Z","steps":["trace[1292910267] 'range keys from in-memory index tree' (duration: 161.150711ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:05:36.75935Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18085} {"level":"info","ts":"2026-07-01T08:05:36.890161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18085,"took":"125.596081ms","hash":409849609,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":29458432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T08:05:36.890232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":409849609,"revision":18085,"compact-revision":15523} {"level":"info","ts":"2026-07-01T08:08:56.424809Z","caller":"traceutil/trace.go:171","msg":"trace[1056657933] linearizableReadLoop","detail":"{readStateIndex:23165; appliedIndex:23164; }","duration":"114.167842ms","start":"2026-07-01T08:08:56.310599Z","end":"2026-07-01T08:08:56.424767Z","steps":["trace[1056657933] 'read index received' (duration: 113.452165ms)","trace[1056657933] 'applied index is now lower than readState.Index' (duration: 714.047µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:08:56.424842Z","caller":"traceutil/trace.go:171","msg":"trace[221437623] transaction","detail":"{read_only:false; response_revision:22418; number_of_response:1; }","duration":"228.900787ms","start":"2026-07-01T08:08:56.195908Z","end":"2026-07-01T08:08:56.424809Z","steps":["trace[221437623] 'process raft request' (duration: 228.274892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:08:56.425181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.546131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T08:08:56.425336Z","caller":"traceutil/trace.go:171","msg":"trace[1512907871] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22418; }","duration":"114.768506ms","start":"2026-07-01T08:08:56.310539Z","end":"2026-07-01T08:08:56.425307Z","steps":["trace[1512907871] 'agreement among raft nodes before linearized reading' (duration: 114.354416ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:10:36.765594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20220} {"level":"info","ts":"2026-07-01T08:10:36.877002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20220,"took":"107.52306ms","hash":1399901266,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":43630592,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-01T08:10:36.877116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1399901266,"revision":20220,"compact-revision":18085} {"level":"info","ts":"2026-07-01T08:12:26.15722Z","caller":"traceutil/trace.go:171","msg":"trace[504746842] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"107.412238ms","start":"2026-07-01T08:12:26.049757Z","end":"2026-07-01T08:12:26.157169Z","steps":["trace[504746842] 'process raft request' (duration: 106.929966ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:26.443828Z","caller":"traceutil/trace.go:171","msg":"trace[1790461666] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"281.480848ms","start":"2026-07-01T08:12:26.162309Z","end":"2026-07-01T08:12:26.44379Z","steps":["trace[1790461666] 'process raft request' (duration: 185.677081ms)","trace[1790461666] 'compare' (duration: 95.402578ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:26.443973Z","caller":"traceutil/trace.go:171","msg":"trace[900015973] linearizableReadLoop","detail":"{readStateIndex:25364; appliedIndex:25360; }","duration":"254.728001ms","start":"2026-07-01T08:12:26.189206Z","end":"2026-07-01T08:12:26.443934Z","steps":["trace[900015973] 'read index received' (duration: 158.879033ms)","trace[900015973] 'applied index is now lower than readState.Index' (duration: 95.848018ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:26.444152Z","caller":"traceutil/trace.go:171","msg":"trace[43694619] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"280.69973ms","start":"2026-07-01T08:12:26.163387Z","end":"2026-07-01T08:12:26.444087Z","steps":["trace[43694619] 'process raft request' (duration: 280.429643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:26.444234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.003667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-01T08:12:26.444269Z","caller":"traceutil/trace.go:171","msg":"trace[74622973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24560; }","duration":"255.112051ms","start":"2026-07-01T08:12:26.189147Z","end":"2026-07-01T08:12:26.444259Z","steps":["trace[74622973] 'agreement among raft nodes before linearized reading' (duration: 254.917286ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:26.444398Z","caller":"traceutil/trace.go:171","msg":"trace[1193925212] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"279.221063ms","start":"2026-07-01T08:12:26.165166Z","end":"2026-07-01T08:12:26.444387Z","steps":["trace[1193925212] 'process raft request' (duration: 278.69432ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:26.444394Z","caller":"traceutil/trace.go:171","msg":"trace[1373686886] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"280.992786ms","start":"2026-07-01T08:12:26.163382Z","end":"2026-07-01T08:12:26.444375Z","steps":["trace[1373686886] 'process raft request' (duration: 280.31774ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:26.444489Z","caller":"traceutil/trace.go:171","msg":"trace[1987648409] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"101.774332ms","start":"2026-07-01T08:12:26.342706Z","end":"2026-07-01T08:12:26.444481Z","steps":["trace[1987648409] 'process raft request' (duration: 101.188608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:26.739919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.813366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:12:26.740021Z","caller":"traceutil/trace.go:171","msg":"trace[710154976] linearizableReadLoop","detail":"{readStateIndex:25366; appliedIndex:25365; }","duration":"263.500164ms","start":"2026-07-01T08:12:26.476503Z","end":"2026-07-01T08:12:26.740003Z","steps":["trace[710154976] 'read index received' (duration: 134.456493ms)","trace[710154976] 'applied index is now lower than readState.Index' (duration: 129.042251ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:26.740271Z","caller":"traceutil/trace.go:171","msg":"trace[988450167] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"288.958399ms","start":"2026-07-01T08:12:26.451198Z","end":"2026-07-01T08:12:26.740156Z","steps":["trace[988450167] 'process raft request' (duration: 159.822396ms)","trace[988450167] 'compare' (duration: 128.542109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:26.740418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.382905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-01T08:12:26.740507Z","caller":"traceutil/trace.go:171","msg":"trace[575532630] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24561; }","duration":"221.519068ms","start":"2026-07-01T08:12:26.518963Z","end":"2026-07-01T08:12:26.740482Z","steps":["trace[575532630] 'agreement among raft nodes before linearized reading' (duration: 221.295243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:26.740747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.241011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:26.740792Z","caller":"traceutil/trace.go:171","msg":"trace[1769976836] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24561; }","duration":"264.329023ms","start":"2026-07-01T08:12:26.476448Z","end":"2026-07-01T08:12:26.740777Z","steps":["trace[1769976836] 'agreement among raft nodes before linearized reading' (duration: 264.264512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:26.741027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.299451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T08:12:26.741065Z","caller":"traceutil/trace.go:171","msg":"trace[1103601299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24561; }","duration":"206.351352ms","start":"2026-07-01T08:12:26.534704Z","end":"2026-07-01T08:12:26.741055Z","steps":["trace[1103601299] 'agreement among raft nodes before linearized reading' (duration: 206.238209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:27.027193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.548787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:12:27.027303Z","caller":"traceutil/trace.go:171","msg":"trace[1007138026] linearizableReadLoop","detail":"{readStateIndex:25367; appliedIndex:25366; }","duration":"277.553663ms","start":"2026-07-01T08:12:26.749734Z","end":"2026-07-01T08:12:27.027288Z","steps":["trace[1007138026] 'read index received' (duration: 120.781831ms)","trace[1007138026] 'applied index is now lower than readState.Index' (duration: 156.770782ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:27.02752Z","caller":"traceutil/trace.go:171","msg":"trace[562269825] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"278.510886ms","start":"2026-07-01T08:12:26.748996Z","end":"2026-07-01T08:12:27.027506Z","steps":["trace[562269825] 'process raft request' (duration: 121.57352ms)","trace[562269825] 'compare' (duration: 155.893921ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:27.027784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.043436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-07-01T08:12:27.027813Z","caller":"traceutil/trace.go:171","msg":"trace[1400196823] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24562; }","duration":"278.087267ms","start":"2026-07-01T08:12:26.749716Z","end":"2026-07-01T08:12:27.027804Z","steps":["trace[1400196823] 'agreement among raft nodes before linearized reading' (duration: 277.963043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:27.028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.412647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:27.028093Z","caller":"traceutil/trace.go:171","msg":"trace[1877190348] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24562; }","duration":"226.569271ms","start":"2026-07-01T08:12:26.801506Z","end":"2026-07-01T08:12:27.028075Z","steps":["trace[1877190348] 'agreement among raft nodes before linearized reading' (duration: 226.387687ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.196063Z","caller":"traceutil/trace.go:171","msg":"trace[1091406613] linearizableReadLoop","detail":"{readStateIndex:25368; appliedIndex:25367; }","duration":"129.707538ms","start":"2026-07-01T08:12:27.066325Z","end":"2026-07-01T08:12:27.196032Z","steps":["trace[1091406613] 'read index received' (duration: 74.346149ms)","trace[1091406613] 'applied index is now lower than readState.Index' (duration: 55.359939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:27.196454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.106777ms","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-07-01T08:12:27.196554Z","caller":"traceutil/trace.go:171","msg":"trace[1279431907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24563; }","duration":"130.256231ms","start":"2026-07-01T08:12:27.066273Z","end":"2026-07-01T08:12:27.196529Z","steps":["trace[1279431907] 'agreement among raft nodes before linearized reading' (duration: 129.955014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:27.196678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.040295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-01T08:12:27.196811Z","caller":"traceutil/trace.go:171","msg":"trace[1779643422] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"163.157436ms","start":"2026-07-01T08:12:27.033635Z","end":"2026-07-01T08:12:27.196793Z","steps":["trace[1779643422] 'process raft request' (duration: 107.113621ms)","trace[1779643422] 'compare' (duration: 55.01946ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:27.196827Z","caller":"traceutil/trace.go:171","msg":"trace[2070340190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24563; }","duration":"125.249359ms","start":"2026-07-01T08:12:27.07154Z","end":"2026-07-01T08:12:27.196789Z","steps":["trace[2070340190] 'agreement among raft nodes before linearized reading' (duration: 124.781808ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.392877Z","caller":"traceutil/trace.go:171","msg":"trace[593356255] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"191.357818ms","start":"2026-07-01T08:12:27.201473Z","end":"2026-07-01T08:12:27.39283Z","steps":["trace[593356255] 'process raft request' (duration: 108.77148ms)","trace[593356255] 'compare' (duration: 82.380473ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:27.4632Z","caller":"traceutil/trace.go:171","msg":"trace[875878811] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"260.288546ms","start":"2026-07-01T08:12:27.202873Z","end":"2026-07-01T08:12:27.463162Z","steps":["trace[875878811] 'process raft request' (duration: 260.004889ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.463531Z","caller":"traceutil/trace.go:171","msg":"trace[1028942720] linearizableReadLoop","detail":"{readStateIndex:25370; appliedIndex:25368; }","duration":"258.664226ms","start":"2026-07-01T08:12:27.204821Z","end":"2026-07-01T08:12:27.463485Z","steps":["trace[1028942720] 'read index received' (duration: 105.325677ms)","trace[1028942720] 'applied index is now lower than readState.Index' (duration: 153.335769ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:27.4636Z","caller":"traceutil/trace.go:171","msg":"trace[1892002197] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"221.807274ms","start":"2026-07-01T08:12:27.241743Z","end":"2026-07-01T08:12:27.46355Z","steps":["trace[1892002197] 'process raft request' (duration: 221.347023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:27.463828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.982433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-07-01T08:12:27.463862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.483447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:27.463893Z","caller":"traceutil/trace.go:171","msg":"trace[257843103] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24566; }","duration":"259.092596ms","start":"2026-07-01T08:12:27.204786Z","end":"2026-07-01T08:12:27.463878Z","steps":["trace[257843103] 'agreement among raft nodes before linearized reading' (duration: 258.860311ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.46395Z","caller":"traceutil/trace.go:171","msg":"trace[148220495] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24566; }","duration":"147.628051ms","start":"2026-07-01T08:12:27.316298Z","end":"2026-07-01T08:12:27.463926Z","steps":["trace[148220495] 'agreement among raft nodes before linearized reading' (duration: 147.441037ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.924942Z","caller":"traceutil/trace.go:171","msg":"trace[1261028472] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"228.367524ms","start":"2026-07-01T08:12:27.696539Z","end":"2026-07-01T08:12:27.924906Z","steps":["trace[1261028472] 'process raft request' (duration: 228.153049ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.945276Z","caller":"traceutil/trace.go:171","msg":"trace[1036647491] linearizableReadLoop","detail":"{readStateIndex:25373; appliedIndex:25371; }","duration":"126.253014ms","start":"2026-07-01T08:12:27.818978Z","end":"2026-07-01T08:12:27.945231Z","steps":["trace[1036647491] 'read index received' (duration: 105.604904ms)","trace[1036647491] 'applied index is now lower than readState.Index' (duration: 20.646529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:27.945504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.481719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:27.945561Z","caller":"traceutil/trace.go:171","msg":"trace[1226296294] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24568; }","duration":"126.618682ms","start":"2026-07-01T08:12:27.818924Z","end":"2026-07-01T08:12:27.945543Z","steps":["trace[1226296294] 'agreement among raft nodes before linearized reading' (duration: 126.481109ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:27.945903Z","caller":"traceutil/trace.go:171","msg":"trace[15422530] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"246.133114ms","start":"2026-07-01T08:12:27.699731Z","end":"2026-07-01T08:12:27.945865Z","steps":["trace[15422530] 'process raft request' (duration: 245.290894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:28.36507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.710258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:12:28.365194Z","caller":"traceutil/trace.go:171","msg":"trace[469563433] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24568; }","duration":"239.936094ms","start":"2026-07-01T08:12:28.125227Z","end":"2026-07-01T08:12:28.365163Z","steps":["trace[469563433] 'count revisions from in-memory index tree' (duration: 239.604806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:28.365198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.84174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:28.365278Z","caller":"traceutil/trace.go:171","msg":"trace[1998373419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24568; }","duration":"278.020464ms","start":"2026-07-01T08:12:28.087237Z","end":"2026-07-01T08:12:28.365257Z","steps":["trace[1998373419] 'range keys from in-memory index tree' (duration: 277.756409ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:28.753551Z","caller":"traceutil/trace.go:171","msg":"trace[1103916052] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"299.031583ms","start":"2026-07-01T08:12:28.454481Z","end":"2026-07-01T08:12:28.753513Z","steps":["trace[1103916052] 'process raft request' (duration: 270.039732ms)","trace[1103916052] 'compare' (duration: 28.632122ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:28.753616Z","caller":"traceutil/trace.go:171","msg":"trace[576588524] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"299.063094ms","start":"2026-07-01T08:12:28.454516Z","end":"2026-07-01T08:12:28.753579Z","steps":["trace[576588524] 'process raft request' (duration: 298.790747ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:28.753719Z","caller":"traceutil/trace.go:171","msg":"trace[1991050014] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"298.990302ms","start":"2026-07-01T08:12:28.454701Z","end":"2026-07-01T08:12:28.753691Z","steps":["trace[1991050014] 'process raft request' (duration: 298.654823ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:28.754467Z","caller":"traceutil/trace.go:171","msg":"trace[1718869056] linearizableReadLoop","detail":"{readStateIndex:25378; appliedIndex:25374; }","duration":"213.384571ms","start":"2026-07-01T08:12:28.541067Z","end":"2026-07-01T08:12:28.754452Z","steps":["trace[1718869056] 'read index received' (duration: 183.402505ms)","trace[1718869056] 'applied index is now lower than readState.Index' (duration: 29.981076ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:28.754724Z","caller":"traceutil/trace.go:171","msg":"trace[1473759439] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"298.10939ms","start":"2026-07-01T08:12:28.456599Z","end":"2026-07-01T08:12:28.754709Z","steps":["trace[1473759439] 'process raft request' (duration: 297.769972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:28.754933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.847502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:28.755057Z","caller":"traceutil/trace.go:171","msg":"trace[1009697783] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24573; }","duration":"213.983236ms","start":"2026-07-01T08:12:28.541049Z","end":"2026-07-01T08:12:28.755032Z","steps":["trace[1009697783] 'agreement among raft nodes before linearized reading' (duration: 213.819632ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:28.880753Z","caller":"traceutil/trace.go:171","msg":"trace[2064074136] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"120.342111ms","start":"2026-07-01T08:12:28.760369Z","end":"2026-07-01T08:12:28.880711Z","steps":["trace[2064074136] 'process raft request' (duration: 107.035289ms)","trace[2064074136] 'compare' (duration: 13.135708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:29.330964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.924703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-01T08:12:29.331069Z","caller":"traceutil/trace.go:171","msg":"trace[68070793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24575; }","duration":"130.043426ms","start":"2026-07-01T08:12:29.200972Z","end":"2026-07-01T08:12:29.331016Z","steps":["trace[68070793] 'range keys from in-memory index tree' (duration: 129.738409ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:30.158433Z","caller":"traceutil/trace.go:171","msg":"trace[1755267594] linearizableReadLoop","detail":"{readStateIndex:25387; appliedIndex:25386; }","duration":"207.364176ms","start":"2026-07-01T08:12:29.951044Z","end":"2026-07-01T08:12:30.158409Z","steps":["trace[1755267594] 'read index received' (duration: 204.356333ms)","trace[1755267594] 'applied index is now lower than readState.Index' (duration: 3.006913ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:30.158547Z","caller":"traceutil/trace.go:171","msg":"trace[1012717220] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"223.977277ms","start":"2026-07-01T08:12:29.934551Z","end":"2026-07-01T08:12:30.158528Z","steps":["trace[1012717220] 'process raft request' (duration: 220.901333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:30.158822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.760845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T08:12:30.158886Z","caller":"traceutil/trace.go:171","msg":"trace[2034950296] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24581; }","duration":"207.885567ms","start":"2026-07-01T08:12:29.950978Z","end":"2026-07-01T08:12:30.158864Z","steps":["trace[2034950296] 'agreement among raft nodes before linearized reading' (duration: 207.688473ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:30.641799Z","caller":"traceutil/trace.go:171","msg":"trace[2055693315] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"156.861284ms","start":"2026-07-01T08:12:30.484911Z","end":"2026-07-01T08:12:30.641773Z","steps":["trace[2055693315] 'process raft request' (duration: 156.626247ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:30.662926Z","caller":"traceutil/trace.go:171","msg":"trace[826267250] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"118.548117ms","start":"2026-07-01T08:12:30.544348Z","end":"2026-07-01T08:12:30.662896Z","steps":["trace[826267250] 'process raft request' (duration: 118.277671ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:31.032484Z","caller":"traceutil/trace.go:171","msg":"trace[594549339] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"271.61308ms","start":"2026-07-01T08:12:30.760847Z","end":"2026-07-01T08:12:31.03246Z","steps":["trace[594549339] 'process raft request' (duration: 219.021698ms)","trace[594549339] 'compare' (duration: 52.397707ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:31.032813Z","caller":"traceutil/trace.go:171","msg":"trace[391570296] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"271.913997ms","start":"2026-07-01T08:12:30.760872Z","end":"2026-07-01T08:12:31.032786Z","steps":["trace[391570296] 'process raft request' (duration: 271.540638ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:31.032843Z","caller":"traceutil/trace.go:171","msg":"trace[2129818892] linearizableReadLoop","detail":"{readStateIndex:25395; appliedIndex:25392; }","duration":"183.530279ms","start":"2026-07-01T08:12:30.849289Z","end":"2026-07-01T08:12:31.03282Z","steps":["trace[2129818892] 'read index received' (duration: 130.532807ms)","trace[2129818892] 'applied index is now lower than readState.Index' (duration: 52.996242ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:31.032933Z","caller":"traceutil/trace.go:171","msg":"trace[788957378] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"271.902637ms","start":"2026-07-01T08:12:30.761018Z","end":"2026-07-01T08:12:31.032921Z","steps":["trace[788957378] 'process raft request' (duration: 271.675252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.03306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.752094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:12:31.033097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.819065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T08:12:31.033113Z","caller":"traceutil/trace.go:171","msg":"trace[856814856] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24589; }","duration":"183.878697ms","start":"2026-07-01T08:12:30.849219Z","end":"2026-07-01T08:12:31.033098Z","steps":["trace[856814856] 'agreement among raft nodes before linearized reading' (duration: 183.730094ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:31.03312Z","caller":"traceutil/trace.go:171","msg":"trace[1032352061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24589; }","duration":"147.875257ms","start":"2026-07-01T08:12:30.885237Z","end":"2026-07-01T08:12:31.033112Z","steps":["trace[1032352061] 'agreement among raft nodes before linearized reading' (duration: 147.746564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.465332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.940277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:12:31.465501Z","caller":"traceutil/trace.go:171","msg":"trace[1123867299] linearizableReadLoop","detail":"{readStateIndex:25396; appliedIndex:25395; }","duration":"391.711365ms","start":"2026-07-01T08:12:31.073763Z","end":"2026-07-01T08:12:31.465474Z","steps":["trace[1123867299] 'read index received' (duration: 100.497081ms)","trace[1123867299] 'applied index is now lower than readState.Index' (duration: 291.211124ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:31.465658Z","caller":"traceutil/trace.go:171","msg":"trace[1544877477] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"428.421893ms","start":"2026-07-01T08:12:31.037201Z","end":"2026-07-01T08:12:31.465623Z","steps":["trace[1544877477] 'process raft request' (duration: 137.044555ms)","trace[1544877477] 'compare' (duration: 290.67626ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:31.465876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:31.037179Z","time spent":"428.573906ms","remote":"127.0.0.1:41082","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-07-01T08:12:31.465896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.533186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:31.465957Z","caller":"traceutil/trace.go:171","msg":"trace[348516297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24590; }","duration":"378.636729ms","start":"2026-07-01T08:12:31.087301Z","end":"2026-07-01T08:12:31.465938Z","steps":["trace[348516297] 'agreement among raft nodes before linearized reading' (duration: 378.529166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.466017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:31.087277Z","time spent":"378.72724ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T08:12:31.465997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.164988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:31.466135Z","caller":"traceutil/trace.go:171","msg":"trace[1937659432] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24590; }","duration":"185.349273ms","start":"2026-07-01T08:12:31.280747Z","end":"2026-07-01T08:12:31.466096Z","steps":["trace[1937659432] 'agreement among raft nodes before linearized reading' (duration: 185.114958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.46634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.093602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:31.466404Z","caller":"traceutil/trace.go:171","msg":"trace[1172002264] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:24590; }","duration":"260.204074ms","start":"2026-07-01T08:12:31.206184Z","end":"2026-07-01T08:12:31.466388Z","steps":["trace[1172002264] 'agreement among raft nodes before linearized reading' (duration: 260.101462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.466623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.056879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:31.466666Z","caller":"traceutil/trace.go:171","msg":"trace[639616424] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24590; }","duration":"231.131011ms","start":"2026-07-01T08:12:31.235524Z","end":"2026-07-01T08:12:31.466655Z","steps":["trace[639616424] 'agreement among raft nodes before linearized reading' (duration: 231.052129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.467104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.340514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T08:12:31.467177Z","caller":"traceutil/trace.go:171","msg":"trace[1537675932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24590; }","duration":"393.457517ms","start":"2026-07-01T08:12:31.0737Z","end":"2026-07-01T08:12:31.467157Z","steps":["trace[1537675932] 'agreement among raft nodes before linearized reading' (duration: 391.92519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.467227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:31.073676Z","time spent":"393.532329ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T08:12:31.676174Z","caller":"traceutil/trace.go:171","msg":"trace[1468035650] linearizableReadLoop","detail":"{readStateIndex:25398; appliedIndex:25396; }","duration":"195.792886ms","start":"2026-07-01T08:12:31.480362Z","end":"2026-07-01T08:12:31.676155Z","steps":["trace[1468035650] 'read index received' (duration: 162.150142ms)","trace[1468035650] 'applied index is now lower than readState.Index' (duration: 33.641624ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:31.676395Z","caller":"traceutil/trace.go:171","msg":"trace[905400505] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"204.634639ms","start":"2026-07-01T08:12:31.471749Z","end":"2026-07-01T08:12:31.676384Z","steps":["trace[905400505] 'process raft request' (duration: 170.73092ms)","trace[905400505] 'compare' (duration: 33.258624ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:31.67655Z","caller":"traceutil/trace.go:171","msg":"trace[944917566] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"204.707191ms","start":"2026-07-01T08:12:31.471834Z","end":"2026-07-01T08:12:31.676541Z","steps":["trace[944917566] 'process raft request' (duration: 204.207509ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:31.676659Z","caller":"traceutil/trace.go:171","msg":"trace[651438682] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"154.186889ms","start":"2026-07-01T08:12:31.52246Z","end":"2026-07-01T08:12:31.676646Z","steps":["trace[651438682] 'process raft request' (duration: 153.633425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.676808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.432881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T08:12:31.676839Z","caller":"traceutil/trace.go:171","msg":"trace[1628066831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24593; }","duration":"196.500242ms","start":"2026-07-01T08:12:31.480332Z","end":"2026-07-01T08:12:31.676832Z","steps":["trace[1628066831] 'agreement among raft nodes before linearized reading' (duration: 196.390889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:31.676908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.195626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T08:12:31.676983Z","caller":"traceutil/trace.go:171","msg":"trace[153274675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24593; }","duration":"115.318589ms","start":"2026-07-01T08:12:31.561647Z","end":"2026-07-01T08:12:31.676966Z","steps":["trace[153274675] 'agreement among raft nodes before linearized reading' (duration: 115.146605ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:32.082598Z","caller":"traceutil/trace.go:171","msg":"trace[2029944764] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"399.010893ms","start":"2026-07-01T08:12:31.683557Z","end":"2026-07-01T08:12:32.082568Z","steps":["trace[2029944764] 'process raft request' (duration: 398.820628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:32.082789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:31.683539Z","time spent":"399.155586ms","remote":"127.0.0.1:41082","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-07-01T08:12:32.096923Z","caller":"traceutil/trace.go:171","msg":"trace[1034567657] linearizableReadLoop","detail":"{readStateIndex:25401; appliedIndex:25399; }","duration":"100.116132ms","start":"2026-07-01T08:12:31.996777Z","end":"2026-07-01T08:12:32.096893Z","steps":["trace[1034567657] 'read index received' (duration: 85.609101ms)","trace[1034567657] 'applied index is now lower than readState.Index' (duration: 14.505311ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:32.097036Z","caller":"traceutil/trace.go:171","msg":"trace[266047675] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"409.671689ms","start":"2026-07-01T08:12:31.687325Z","end":"2026-07-01T08:12:32.096996Z","steps":["trace[266047675] 'process raft request' (duration: 409.336871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:32.097271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.45164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-01T08:12:32.097291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:31.687306Z","time spent":"409.839453ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:12:32.097341Z","caller":"traceutil/trace.go:171","msg":"trace[744147797] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24595; }","duration":"100.562883ms","start":"2026-07-01T08:12:31.996768Z","end":"2026-07-01T08:12:32.097331Z","steps":["trace[744147797] 'agreement among raft nodes before linearized reading' (duration: 100.303297ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:32.298316Z","caller":"traceutil/trace.go:171","msg":"trace[1214270043] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"132.902355ms","start":"2026-07-01T08:12:32.165376Z","end":"2026-07-01T08:12:32.298279Z","steps":["trace[1214270043] 'process raft request' (duration: 119.188043ms)","trace[1214270043] 'compare' (duration: 13.319473ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:42.717402Z","caller":"traceutil/trace.go:171","msg":"trace[154694597] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25477; }","duration":"360.576782ms","start":"2026-07-01T08:12:42.356787Z","end":"2026-07-01T08:12:42.717364Z","steps":["trace[154694597] 'read index received' (duration: 360.375477ms)","trace[154694597] 'applied index is now lower than readState.Index' (duration: 200.045µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:42.717496Z","caller":"traceutil/trace.go:171","msg":"trace[2135280270] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"374.55323ms","start":"2026-07-01T08:12:42.342882Z","end":"2026-07-01T08:12:42.717435Z","steps":["trace[2135280270] 'process raft request' (duration: 374.277033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:42.717818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:42.342836Z","time spent":"374.848247ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:12:42.7179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.057903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T08:12:42.718013Z","caller":"traceutil/trace.go:171","msg":"trace[1440204094] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24670; }","duration":"361.221877ms","start":"2026-07-01T08:12:42.356762Z","end":"2026-07-01T08:12:42.717984Z","steps":["trace[1440204094] 'agreement among raft nodes before linearized reading' (duration: 360.828298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:42.718073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:42.35673Z","time spent":"361.33356ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-01T08:12:43.34273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.850373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:12:43.34285Z","caller":"traceutil/trace.go:171","msg":"trace[1895739610] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"487.245695ms","start":"2026-07-01T08:12:42.855582Z","end":"2026-07-01T08:12:43.342828Z","steps":["trace[1895739610] 'process raft request' (duration: 225.201707ms)","trace[1895739610] 'compare' (duration: 260.924691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:43.34323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:42.85555Z","time spent":"487.623725ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:12:43.344012Z","caller":"traceutil/trace.go:171","msg":"trace[1807412031] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"261.528616ms","start":"2026-07-01T08:12:43.082456Z","end":"2026-07-01T08:12:43.343985Z","steps":["trace[1807412031] 'process raft request' (duration: 261.352521ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:43.676287Z","caller":"traceutil/trace.go:171","msg":"trace[1392595538] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25481; }","duration":"434.281255ms","start":"2026-07-01T08:12:43.241977Z","end":"2026-07-01T08:12:43.676258Z","steps":["trace[1392595538] 'read index received' (duration: 101.860825ms)","trace[1392595538] 'applied index is now lower than readState.Index' (duration: 332.41897ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:43.676486Z","caller":"traceutil/trace.go:171","msg":"trace[637881041] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"593.300771ms","start":"2026-07-01T08:12:43.083169Z","end":"2026-07-01T08:12:43.676469Z","steps":["trace[637881041] 'process raft request' (duration: 556.205993ms)","trace[637881041] 'compare' (duration: 36.437071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:43.676645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.657714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:43.67672Z","caller":"traceutil/trace.go:171","msg":"trace[191472356] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24676; }","duration":"434.792788ms","start":"2026-07-01T08:12:43.241911Z","end":"2026-07-01T08:12:43.676704Z","steps":["trace[191472356] 'agreement among raft nodes before linearized reading' (duration: 434.652434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:43.676751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:43.241883Z","time spent":"434.86247ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-01T08:12:43.676669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:43.083131Z","time spent":"593.409784ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:12:43.676788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.978435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-07-01T08:12:43.676877Z","caller":"traceutil/trace.go:171","msg":"trace[487852412] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24676; }","duration":"283.122688ms","start":"2026-07-01T08:12:43.393728Z","end":"2026-07-01T08:12:43.67685Z","steps":["trace[487852412] 'agreement among raft nodes before linearized reading' (duration: 282.838991ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:43.676875Z","caller":"traceutil/trace.go:171","msg":"trace[1813491860] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"593.485066ms","start":"2026-07-01T08:12:43.083349Z","end":"2026-07-01T08:12:43.676834Z","steps":["trace[1813491860] 'process raft request' (duration: 592.779648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:43.676991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.822693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T08:12:43.677034Z","caller":"traceutil/trace.go:171","msg":"trace[468438892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24676; }","duration":"144.890945ms","start":"2026-07-01T08:12:43.532133Z","end":"2026-07-01T08:12:43.677024Z","steps":["trace[468438892] 'agreement among raft nodes before linearized reading' (duration: 144.67655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:43.677095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:43.083316Z","time spent":"593.6655ms","remote":"127.0.0.1:41082","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-07-01T08:12:43.881088Z","caller":"traceutil/trace.go:171","msg":"trace[394013938] linearizableReadLoop","detail":"{readStateIndex:25488; appliedIndex:25486; }","duration":"109.902909ms","start":"2026-07-01T08:12:43.771155Z","end":"2026-07-01T08:12:43.881058Z","steps":["trace[394013938] 'read index received' (duration: 7.245506ms)","trace[394013938] 'applied index is now lower than readState.Index' (duration: 102.656143ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:43.881241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.062133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:43.881285Z","caller":"traceutil/trace.go:171","msg":"trace[446938964] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:24680; }","duration":"110.149705ms","start":"2026-07-01T08:12:43.77112Z","end":"2026-07-01T08:12:43.881269Z","steps":["trace[446938964] 'agreement among raft nodes before linearized reading' (duration: 110.054433ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:43.881252Z","caller":"traceutil/trace.go:171","msg":"trace[1762081092] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"110.498173ms","start":"2026-07-01T08:12:43.770687Z","end":"2026-07-01T08:12:43.881186Z","steps":["trace[1762081092] 'process raft request' (duration: 110.266728ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:44.442555Z","caller":"traceutil/trace.go:171","msg":"trace[602878616] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"194.688299ms","start":"2026-07-01T08:12:44.247833Z","end":"2026-07-01T08:12:44.442522Z","steps":["trace[602878616] 'process raft request' (duration: 194.483994ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:44.443937Z","caller":"traceutil/trace.go:171","msg":"trace[1416780947] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"195.673042ms","start":"2026-07-01T08:12:44.248209Z","end":"2026-07-01T08:12:44.443883Z","steps":["trace[1416780947] 'process raft request' (duration: 195.367825ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:50.906223Z","caller":"traceutil/trace.go:171","msg":"trace[1452158900] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"126.282035ms","start":"2026-07-01T08:12:50.779881Z","end":"2026-07-01T08:12:50.906163Z","steps":["trace[1452158900] 'process raft request' (duration: 125.125407ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:50.90706Z","caller":"traceutil/trace.go:171","msg":"trace[62016989] linearizableReadLoop","detail":"{readStateIndex:25600; appliedIndex:25598; }","duration":"123.208921ms","start":"2026-07-01T08:12:50.783826Z","end":"2026-07-01T08:12:50.907035Z","steps":["trace[62016989] 'read index received' (duration: 121.204342ms)","trace[62016989] 'applied index is now lower than readState.Index' (duration: 2.003139ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:50.90732Z","caller":"traceutil/trace.go:171","msg":"trace[707773265] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"125.920016ms","start":"2026-07-01T08:12:50.78135Z","end":"2026-07-01T08:12:50.90727Z","steps":["trace[707773265] 'process raft request' (duration: 125.576168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:50.907786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.943817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T08:12:50.907903Z","caller":"traceutil/trace.go:171","msg":"trace[1979289593] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24789; }","duration":"107.126931ms","start":"2026-07-01T08:12:50.800757Z","end":"2026-07-01T08:12:50.907884Z","steps":["trace[1979289593] 'agreement among raft nodes before linearized reading' (duration: 106.570288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:50.907609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.737673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ecee3f6-ce12-4ba8-b133-de61b41e5f8a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-01T08:12:50.908224Z","caller":"traceutil/trace.go:171","msg":"trace[1223422525] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ecee3f6-ce12-4ba8-b133-de61b41e5f8a; range_end:; response_count:1; response_revision:24789; }","duration":"124.436979ms","start":"2026-07-01T08:12:50.783757Z","end":"2026-07-01T08:12:50.908194Z","steps":["trace[1223422525] 'agreement among raft nodes before linearized reading' (duration: 123.526618ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:51.151158Z","caller":"traceutil/trace.go:171","msg":"trace[209073596] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"236.357237ms","start":"2026-07-01T08:12:50.914767Z","end":"2026-07-01T08:12:51.151124Z","steps":["trace[209073596] 'process raft request' (duration: 162.826989ms)","trace[209073596] 'compare' (duration: 73.381145ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:51.416424Z","caller":"traceutil/trace.go:171","msg":"trace[2046163690] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"498.432536ms","start":"2026-07-01T08:12:50.917939Z","end":"2026-07-01T08:12:51.416371Z","steps":["trace[2046163690] 'process raft request' (duration: 427.260724ms)","trace[2046163690] 'compare' (duration: 70.861854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:51.416632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:50.917927Z","time spent":"498.602399ms","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:12:51.430165Z","caller":"traceutil/trace.go:171","msg":"trace[1559136409] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"284.05726ms","start":"2026-07-01T08:12:51.146081Z","end":"2026-07-01T08:12:51.430139Z","steps":["trace[1559136409] 'process raft request' (duration: 283.928937ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:51.606255Z","caller":"traceutil/trace.go:171","msg":"trace[1290202547] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"229.419869ms","start":"2026-07-01T08:12:51.376813Z","end":"2026-07-01T08:12:51.606233Z","steps":["trace[1290202547] 'process raft request' (duration: 229.365838ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:51.606279Z","caller":"traceutil/trace.go:171","msg":"trace[970848042] transaction","detail":"{read_only:false; response_revision:24793; number_of_response:1; }","duration":"230.734951ms","start":"2026-07-01T08:12:51.375506Z","end":"2026-07-01T08:12:51.606241Z","steps":["trace[970848042] 'process raft request' (duration: 182.930835ms)","trace[970848042] 'compare' (duration: 47.573251ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:51.70379Z","caller":"traceutil/trace.go:171","msg":"trace[73789021] transaction","detail":"{read_only:false; response_revision:24795; number_of_response:1; }","duration":"160.546063ms","start":"2026-07-01T08:12:51.543208Z","end":"2026-07-01T08:12:51.703755Z","steps":["trace[73789021] 'process raft request' (duration: 160.359158ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:51.939388Z","caller":"traceutil/trace.go:171","msg":"trace[1767095722] linearizableReadLoop","detail":"{readStateIndex:25609; appliedIndex:25608; }","duration":"135.62777ms","start":"2026-07-01T08:12:51.803739Z","end":"2026-07-01T08:12:51.939367Z","steps":["trace[1767095722] 'read index received' (duration: 127.78877ms)","trace[1767095722] 'applied index is now lower than readState.Index' (duration: 7.83839ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:51.939487Z","caller":"traceutil/trace.go:171","msg":"trace[464773139] transaction","detail":"{read_only:false; response_revision:24798; number_of_response:1; }","duration":"136.504992ms","start":"2026-07-01T08:12:51.802971Z","end":"2026-07-01T08:12:51.939476Z","steps":["trace[464773139] 'process raft request' (duration: 128.622122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:51.939684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.3371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ecee3f6-ce12-4ba8-b133-de61b41e5f8a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-07-01T08:12:51.939792Z","caller":"traceutil/trace.go:171","msg":"trace[2007923011] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ecee3f6-ce12-4ba8-b133-de61b41e5f8a; range_end:; response_count:1; response_revision:24798; }","duration":"182.481054ms","start":"2026-07-01T08:12:51.757275Z","end":"2026-07-01T08:12:51.939756Z","steps":["trace[2007923011] 'agreement among raft nodes before linearized reading' (duration: 182.226918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:51.939811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.133531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-37111b7e-b06c-49df-9115-076cc081246d\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-07-01T08:12:51.939894Z","caller":"traceutil/trace.go:171","msg":"trace[533036981] range","detail":"{range_begin:/registry/persistentvolumes/pvc-37111b7e-b06c-49df-9115-076cc081246d; range_end:; response_count:1; response_revision:24798; }","duration":"126.209792ms","start":"2026-07-01T08:12:51.813633Z","end":"2026-07-01T08:12:51.939843Z","steps":["trace[533036981] 'agreement among raft nodes before linearized reading' (duration: 126.039688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:51.940095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.511864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T08:12:51.940149Z","caller":"traceutil/trace.go:171","msg":"trace[969700085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24798; }","duration":"139.582645ms","start":"2026-07-01T08:12:51.800558Z","end":"2026-07-01T08:12:51.94014Z","steps":["trace[969700085] 'agreement among raft nodes before linearized reading' (duration: 139.460392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:51.940256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.722451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:51.940335Z","caller":"traceutil/trace.go:171","msg":"trace[1848059105] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24798; }","duration":"168.846353ms","start":"2026-07-01T08:12:51.771456Z","end":"2026-07-01T08:12:51.940302Z","steps":["trace[1848059105] 'agreement among raft nodes before linearized reading' (duration: 168.736031ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:54.188208Z","caller":"traceutil/trace.go:171","msg":"trace[397172516] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"138.166301ms","start":"2026-07-01T08:12:54.050004Z","end":"2026-07-01T08:12:54.188171Z","steps":["trace[397172516] 'process raft request' (duration: 133.702223ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:54.21734Z","caller":"traceutil/trace.go:171","msg":"trace[1595529896] linearizableReadLoop","detail":"{readStateIndex:25626; appliedIndex:25623; }","duration":"128.234141ms","start":"2026-07-01T08:12:54.089073Z","end":"2026-07-01T08:12:54.217307Z","steps":["trace[1595529896] 'read index received' (duration: 94.655278ms)","trace[1595529896] 'applied index is now lower than readState.Index' (duration: 33.577543ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:54.217381Z","caller":"traceutil/trace.go:171","msg":"trace[941968848] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"167.098341ms","start":"2026-07-01T08:12:54.05024Z","end":"2026-07-01T08:12:54.217339Z","steps":["trace[941968848] 'process raft request' (duration: 166.782754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:54.217538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.431946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:54.217606Z","caller":"traceutil/trace.go:171","msg":"trace[728222304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24815; }","duration":"128.525049ms","start":"2026-07-01T08:12:54.089063Z","end":"2026-07-01T08:12:54.217588Z","steps":["trace[728222304] 'agreement among raft nodes before linearized reading' (duration: 128.380956ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:54.217417Z","caller":"traceutil/trace.go:171","msg":"trace[1891254919] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"165.221407ms","start":"2026-07-01T08:12:54.052172Z","end":"2026-07-01T08:12:54.217394Z","steps":["trace[1891254919] 'process raft request' (duration: 165.053293ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.154443Z","caller":"traceutil/trace.go:171","msg":"trace[2033843642] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"232.938984ms","start":"2026-07-01T08:12:56.921477Z","end":"2026-07-01T08:12:57.154416Z","steps":["trace[2033843642] 'process raft request' (duration: 232.7745ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.299987Z","caller":"traceutil/trace.go:171","msg":"trace[1234328196] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"368.604097ms","start":"2026-07-01T08:12:56.931361Z","end":"2026-07-01T08:12:57.299965Z","steps":["trace[1234328196] 'process raft request' (duration: 325.552965ms)","trace[1234328196] 'compare' (duration: 42.788715ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:12:57.300146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:56.931337Z","time spent":"368.720559ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:12:57.301494Z","caller":"traceutil/trace.go:171","msg":"trace[1918424929] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"368.682417ms","start":"2026-07-01T08:12:56.932778Z","end":"2026-07-01T08:12:57.301461Z","steps":["trace[1918424929] 'process raft request' (duration: 367.10743ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.301609Z","caller":"traceutil/trace.go:171","msg":"trace[795051064] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"138.275704ms","start":"2026-07-01T08:12:57.163305Z","end":"2026-07-01T08:12:57.301581Z","steps":["trace[795051064] 'process raft request' (duration: 138.09986ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.301557Z","caller":"traceutil/trace.go:171","msg":"trace[771322021] linearizableReadLoop","detail":"{readStateIndex:25669; appliedIndex:25666; }","duration":"294.495423ms","start":"2026-07-01T08:12:57.007011Z","end":"2026-07-01T08:12:57.301507Z","steps":["trace[771322021] 'read index received' (duration: 147.241002ms)","trace[771322021] 'applied index is now lower than readState.Index' (duration: 147.248851ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:12:57.301708Z","caller":"traceutil/trace.go:171","msg":"trace[484798329] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"138.308105ms","start":"2026-07-01T08:12:57.163327Z","end":"2026-07-01T08:12:57.301635Z","steps":["trace[484798329] 'process raft request' (duration: 138.159882ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.301939Z","caller":"traceutil/trace.go:171","msg":"trace[651471670] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"137.726961ms","start":"2026-07-01T08:12:57.164197Z","end":"2026-07-01T08:12:57.301924Z","steps":["trace[651471670] 'process raft request' (duration: 137.333642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:57.301981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.942304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-07-01T08:12:57.301995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.873387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:12:57.301997Z","caller":"traceutil/trace.go:171","msg":"trace[515389837] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"137.25518ms","start":"2026-07-01T08:12:57.164695Z","end":"2026-07-01T08:12:57.30195Z","steps":["trace[515389837] 'process raft request' (duration: 137.101436ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.302057Z","caller":"traceutil/trace.go:171","msg":"trace[1884043095] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24859; }","duration":"295.035466ms","start":"2026-07-01T08:12:57.007003Z","end":"2026-07-01T08:12:57.302038Z","steps":["trace[1884043095] 'agreement among raft nodes before linearized reading' (duration: 294.605726ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:12:57.302076Z","caller":"traceutil/trace.go:171","msg":"trace[504551238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24860; }","duration":"214.996789ms","start":"2026-07-01T08:12:57.087057Z","end":"2026-07-01T08:12:57.302054Z","steps":["trace[504551238] 'agreement among raft nodes before linearized reading' (duration: 214.886937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:57.302143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.57971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-01T08:12:57.302184Z","caller":"traceutil/trace.go:171","msg":"trace[1104683577] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24860; }","duration":"128.663991ms","start":"2026-07-01T08:12:57.173508Z","end":"2026-07-01T08:12:57.302172Z","steps":["trace[1104683577] 'agreement among raft nodes before linearized reading' (duration: 128.524538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:12:57.301816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:12:56.932755Z","time spent":"368.867501ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:13:28.057142Z","caller":"traceutil/trace.go:171","msg":"trace[123558185] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"127.798931ms","start":"2026-07-01T08:13:27.929306Z","end":"2026-07-01T08:13:28.057105Z","steps":["trace[123558185] 'process raft request' (duration: 127.470943ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:28.070551Z","caller":"traceutil/trace.go:171","msg":"trace[1512200249] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"140.448337ms","start":"2026-07-01T08:13:27.930069Z","end":"2026-07-01T08:13:28.070517Z","steps":["trace[1512200249] 'process raft request' (duration: 140.281493ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:31.404172Z","caller":"traceutil/trace.go:171","msg":"trace[1103424568] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"166.050446ms","start":"2026-07-01T08:13:31.238092Z","end":"2026-07-01T08:13:31.404142Z","steps":["trace[1103424568] 'process raft request' (duration: 165.743229ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:31.928189Z","caller":"traceutil/trace.go:171","msg":"trace[1877446743] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"496.082889ms","start":"2026-07-01T08:13:31.431988Z","end":"2026-07-01T08:13:31.928071Z","steps":["trace[1877446743] 'process raft request' (duration: 488.992907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:31.928762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:31.43195Z","time spent":"496.726555ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:13:31.977563Z","caller":"traceutil/trace.go:171","msg":"trace[878573526] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"542.976783ms","start":"2026-07-01T08:13:31.434518Z","end":"2026-07-01T08:13:31.977494Z","steps":["trace[878573526] 'process raft request' (duration: 542.622435ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:31.977632Z","caller":"traceutil/trace.go:171","msg":"trace[2023889955] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"214.017057ms","start":"2026-07-01T08:13:31.763577Z","end":"2026-07-01T08:13:31.977594Z","steps":["trace[2023889955] 'process raft request' (duration: 213.834502ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:31.977609Z","caller":"traceutil/trace.go:171","msg":"trace[754596882] linearizableReadLoop","detail":"{readStateIndex:25947; appliedIndex:25945; }","duration":"499.799809ms","start":"2026-07-01T08:13:31.477768Z","end":"2026-07-01T08:13:31.977568Z","steps":["trace[754596882] 'read index received' (duration: 443.177179ms)","trace[754596882] 'applied index is now lower than readState.Index' (duration: 56.62145ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:31.977818Z","caller":"traceutil/trace.go:171","msg":"trace[1957843608] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"214.066228ms","start":"2026-07-01T08:13:31.763734Z","end":"2026-07-01T08:13:31.9778Z","steps":["trace[1957843608] 'process raft request' (duration: 213.76518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:31.977829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:31.434504Z","time spent":"543.185139ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:13:31.978171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.383724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T08:13:31.978209Z","caller":"traceutil/trace.go:171","msg":"trace[673003881] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25129; }","duration":"500.468726ms","start":"2026-07-01T08:13:31.477726Z","end":"2026-07-01T08:13:31.978195Z","steps":["trace[673003881] 'agreement among raft nodes before linearized reading' (duration: 500.284562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:31.978231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:31.477712Z","time spent":"500.512767ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-01T08:13:32.319409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.401635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:13:32.319563Z","caller":"traceutil/trace.go:171","msg":"trace[1003297810] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"334.614224ms","start":"2026-07-01T08:13:31.984921Z","end":"2026-07-01T08:13:32.319535Z","steps":["trace[1003297810] 'process raft request' (duration: 205.949372ms)","trace[1003297810] 'compare' (duration: 127.915283ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:32.319644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:31.9849Z","time spent":"334.705696ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:13:32.385901Z","caller":"traceutil/trace.go:171","msg":"trace[132326639] linearizableReadLoop","detail":"{readStateIndex:25952; appliedIndex:25949; }","duration":"298.889849ms","start":"2026-07-01T08:13:32.086981Z","end":"2026-07-01T08:13:32.385871Z","steps":["trace[132326639] 'read index received' (duration: 103.908583ms)","trace[132326639] 'applied index is now lower than readState.Index' (duration: 194.980456ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:32.38601Z","caller":"traceutil/trace.go:171","msg":"trace[125507253] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"301.371519ms","start":"2026-07-01T08:13:32.084577Z","end":"2026-07-01T08:13:32.385948Z","steps":["trace[125507253] 'process raft request' (duration: 301.256946ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:32.386049Z","caller":"traceutil/trace.go:171","msg":"trace[1763764982] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"307.254471ms","start":"2026-07-01T08:13:32.078671Z","end":"2026-07-01T08:13:32.385925Z","steps":["trace[1763764982] 'process raft request' (duration: 307.023736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:32.386063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.077233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:32.386358Z","caller":"traceutil/trace.go:171","msg":"trace[606711152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25132; }","duration":"299.417652ms","start":"2026-07-01T08:13:32.086928Z","end":"2026-07-01T08:13:32.386346Z","steps":["trace[606711152] 'agreement among raft nodes before linearized reading' (duration: 299.066384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:32.386348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.078633Z","time spent":"307.578038ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:13:32.386361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.084554Z","time spent":"301.584425ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:13:32.5892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.98481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:13:32.590152Z","caller":"traceutil/trace.go:171","msg":"trace[1558529816] linearizableReadLoop","detail":"{readStateIndex:25953; appliedIndex:25952; }","duration":"204.143868ms","start":"2026-07-01T08:13:32.385971Z","end":"2026-07-01T08:13:32.590115Z","steps":["trace[1558529816] 'read index received' (duration: 67.081642ms)","trace[1558529816] 'applied index is now lower than readState.Index' (duration: 137.058196ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:32.590244Z","caller":"traceutil/trace.go:171","msg":"trace[1879555712] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"397.810773ms","start":"2026-07-01T08:13:32.192355Z","end":"2026-07-01T08:13:32.590166Z","steps":["trace[1879555712] 'process raft request' (duration: 260.745937ms)","trace[1879555712] 'compare' (duration: 135.57589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:32.590488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.278285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T08:13:32.590565Z","caller":"traceutil/trace.go:171","msg":"trace[972267498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25133; }","duration":"205.404848ms","start":"2026-07-01T08:13:32.385137Z","end":"2026-07-01T08:13:32.590542Z","steps":["trace[972267498] 'agreement among raft nodes before linearized reading' (duration: 205.131721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:32.59052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.495019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:13:32.590747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.19233Z","time spent":"398.181301ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:13:32.59077Z","caller":"traceutil/trace.go:171","msg":"trace[590309305] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25133; }","duration":"238.781015ms","start":"2026-07-01T08:13:32.351944Z","end":"2026-07-01T08:13:32.590725Z","steps":["trace[590309305] 'agreement among raft nodes before linearized reading' (duration: 238.425557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:32.600582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.08745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:32.600669Z","caller":"traceutil/trace.go:171","msg":"trace[1523609049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25134; }","duration":"212.230573ms","start":"2026-07-01T08:13:32.388419Z","end":"2026-07-01T08:13:32.60065Z","steps":["trace[1523609049] 'agreement among raft nodes before linearized reading' (duration: 212.07044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:32.600748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.032041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-01T08:13:32.600802Z","caller":"traceutil/trace.go:171","msg":"trace[1040625734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25134; }","duration":"177.113564ms","start":"2026-07-01T08:13:32.423673Z","end":"2026-07-01T08:13:32.600787Z","steps":["trace[1040625734] 'agreement among raft nodes before linearized reading' (duration: 176.915899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:32.600856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.086445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T08:13:32.600954Z","caller":"traceutil/trace.go:171","msg":"trace[583030964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25134; }","duration":"180.25906ms","start":"2026-07-01T08:13:32.42067Z","end":"2026-07-01T08:13:32.600929Z","steps":["trace[583030964] 'agreement among raft nodes before linearized reading' (duration: 179.975913ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:32.739004Z","caller":"traceutil/trace.go:171","msg":"trace[1207173533] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"143.173683ms","start":"2026-07-01T08:13:32.595798Z","end":"2026-07-01T08:13:32.738972Z","steps":["trace[1207173533] 'process raft request' (duration: 129.976385ms)","trace[1207173533] 'compare' (duration: 13.048084ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:33.012543Z","caller":"traceutil/trace.go:171","msg":"trace[1984125379] linearizableReadLoop","detail":"{readStateIndex:25957; appliedIndex:25954; }","duration":"311.520614ms","start":"2026-07-01T08:13:32.70099Z","end":"2026-07-01T08:13:33.012511Z","steps":["trace[1984125379] 'read index received' (duration: 24.79736ms)","trace[1984125379] 'applied index is now lower than readState.Index' (duration: 286.721964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:33.012837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.832162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-07-01T08:13:33.012896Z","caller":"traceutil/trace.go:171","msg":"trace[174577622] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25137; }","duration":"311.941465ms","start":"2026-07-01T08:13:32.700939Z","end":"2026-07-01T08:13:33.01288Z","steps":["trace[174577622] 'agreement among raft nodes before linearized reading' (duration: 311.7377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.012962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.700922Z","time spent":"312.032987ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-07-01T08:13:33.01301Z","caller":"traceutil/trace.go:171","msg":"trace[1097287180] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"407.458185ms","start":"2026-07-01T08:13:32.605535Z","end":"2026-07-01T08:13:33.012993Z","steps":["trace[1097287180] 'process raft request' (duration: 393.367864ms)","trace[1097287180] 'compare' (duration: 13.108547ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:33.013056Z","caller":"traceutil/trace.go:171","msg":"trace[1239552929] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"407.345653ms","start":"2026-07-01T08:13:32.605675Z","end":"2026-07-01T08:13:33.013021Z","steps":["trace[1239552929] 'process raft request' (duration: 406.747398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.013099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.605505Z","time spent":"407.532047ms","remote":"127.0.0.1:41082","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-07-01T08:13:33.013212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.43624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:13:33.013225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.605652Z","time spent":"407.473225ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:13:33.013243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.257329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-01T08:13:33.013209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.314822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:33.013274Z","caller":"traceutil/trace.go:171","msg":"trace[837835923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25137; }","duration":"292.311429ms","start":"2026-07-01T08:13:32.720953Z","end":"2026-07-01T08:13:33.013264Z","steps":["trace[837835923] 'agreement among raft nodes before linearized reading' (duration: 292.235948ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:33.013306Z","caller":"traceutil/trace.go:171","msg":"trace[1970717792] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25137; }","duration":"309.481815ms","start":"2026-07-01T08:13:32.7038Z","end":"2026-07-01T08:13:33.013282Z","steps":["trace[1970717792] 'agreement among raft nodes before linearized reading' (duration: 309.28937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.013351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.769976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:13:33.013369Z","caller":"traceutil/trace.go:171","msg":"trace[895380298] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25137; }","duration":"114.826607ms","start":"2026-07-01T08:13:32.898537Z","end":"2026-07-01T08:13:33.013364Z","steps":["trace[895380298] 'agreement among raft nodes before linearized reading' (duration: 114.734044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.013362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:32.703781Z","time spent":"309.568537ms","remote":"127.0.0.1:41512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-07-01T08:13:33.013258Z","caller":"traceutil/trace.go:171","msg":"trace[1477537360] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25137; }","duration":"172.45998ms","start":"2026-07-01T08:13:32.840766Z","end":"2026-07-01T08:13:33.013226Z","steps":["trace[1477537360] 'agreement among raft nodes before linearized reading' (duration: 172.416129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.013494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.532264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T08:13:33.013634Z","caller":"traceutil/trace.go:171","msg":"trace[188455725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25137; }","duration":"294.769109ms","start":"2026-07-01T08:13:32.718836Z","end":"2026-07-01T08:13:33.013605Z","steps":["trace[188455725] 'agreement among raft nodes before linearized reading' (duration: 294.3595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.399822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.840743ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:33.399988Z","caller":"traceutil/trace.go:171","msg":"trace[503309658] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25138; }","duration":"152.026497ms","start":"2026-07-01T08:13:33.247939Z","end":"2026-07-01T08:13:33.399965Z","steps":["trace[503309658] 'range keys from in-memory index tree' (duration: 151.817032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.400252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.189839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:13:33.400366Z","caller":"traceutil/trace.go:171","msg":"trace[77130272] linearizableReadLoop","detail":"{readStateIndex:25959; appliedIndex:25958; }","duration":"312.283583ms","start":"2026-07-01T08:13:33.088063Z","end":"2026-07-01T08:13:33.400347Z","steps":["trace[77130272] 'read index received' (duration: 83.872279ms)","trace[77130272] 'applied index is now lower than readState.Index' (duration: 228.409964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:33.400469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.404106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:33.400444Z","caller":"traceutil/trace.go:171","msg":"trace[1176512474] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"380.264127ms","start":"2026-07-01T08:13:33.020141Z","end":"2026-07-01T08:13:33.400405Z","steps":["trace[1176512474] 'process raft request' (duration: 151.827533ms)","trace[1176512474] 'compare' (duration: 227.559474ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:33.400502Z","caller":"traceutil/trace.go:171","msg":"trace[1775636101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25139; }","duration":"312.481127ms","start":"2026-07-01T08:13:33.088009Z","end":"2026-07-01T08:13:33.40049Z","steps":["trace[1775636101] 'agreement among raft nodes before linearized reading' (duration: 312.419996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.400536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:33.087986Z","time spent":"312.542389ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T08:13:33.400595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:33.020128Z","time spent":"380.38733ms","remote":"127.0.0.1:41082","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-07-01T08:13:33.419511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.05036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:13:33.41962Z","caller":"traceutil/trace.go:171","msg":"trace[2087840239] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25140; }","duration":"284.196704ms","start":"2026-07-01T08:13:33.135399Z","end":"2026-07-01T08:13:33.419595Z","steps":["trace[2087840239] 'agreement among raft nodes before linearized reading' (duration: 283.970559ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:33.419633Z","caller":"traceutil/trace.go:171","msg":"trace[5104509] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"224.043749ms","start":"2026-07-01T08:13:33.195552Z","end":"2026-07-01T08:13:33.419596Z","steps":["trace[5104509] 'process raft request' (duration: 223.615589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:33.75828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.615393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:13:33.758365Z","caller":"traceutil/trace.go:171","msg":"trace[874926544] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25140; }","duration":"212.722965ms","start":"2026-07-01T08:13:33.545623Z","end":"2026-07-01T08:13:33.758346Z","steps":["trace[874926544] 'count revisions from in-memory index tree' (duration: 212.49418ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.435978Z","caller":"traceutil/trace.go:171","msg":"trace[1973080287] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"103.059913ms","start":"2026-07-01T08:13:34.332882Z","end":"2026-07-01T08:13:34.435942Z","steps":["trace[1973080287] 'process raft request' (duration: 102.824268ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.666231Z","caller":"traceutil/trace.go:171","msg":"trace[70652748] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"272.830529ms","start":"2026-07-01T08:13:34.393367Z","end":"2026-07-01T08:13:34.666197Z","steps":["trace[70652748] 'process raft request' (duration: 272.717826ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.666215Z","caller":"traceutil/trace.go:171","msg":"trace[749599744] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"273.189547ms","start":"2026-07-01T08:13:34.392985Z","end":"2026-07-01T08:13:34.666175Z","steps":["trace[749599744] 'process raft request' (duration: 183.470687ms)","trace[749599744] 'compare' (duration: 89.226968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:34.938654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.595667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:13:34.938738Z","caller":"traceutil/trace.go:171","msg":"trace[1183097155] linearizableReadLoop","detail":"{readStateIndex:25968; appliedIndex:25967; }","duration":"194.614247ms","start":"2026-07-01T08:13:34.74411Z","end":"2026-07-01T08:13:34.938725Z","steps":["trace[1183097155] 'read index received' (duration: 68.836586ms)","trace[1183097155] 'applied index is now lower than readState.Index' (duration: 125.776391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:34.938902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.799722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T08:13:34.938929Z","caller":"traceutil/trace.go:171","msg":"trace[1508637703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25148; }","duration":"194.869884ms","start":"2026-07-01T08:13:34.744053Z","end":"2026-07-01T08:13:34.938923Z","steps":["trace[1508637703] 'agreement among raft nodes before linearized reading' (duration: 194.736231ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.938845Z","caller":"traceutil/trace.go:171","msg":"trace[1500607105] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"341.688015ms","start":"2026-07-01T08:13:34.59711Z","end":"2026-07-01T08:13:34.938798Z","steps":["trace[1500607105] 'process raft request' (duration: 215.849231ms)","trace[1500607105] 'compare' (duration: 125.180507ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:34.939379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:34.597071Z","time spent":"342.187827ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:13:35.230581Z","caller":"traceutil/trace.go:171","msg":"trace[968465957] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"139.056334ms","start":"2026-07-01T08:13:35.091429Z","end":"2026-07-01T08:13:35.230486Z","steps":["trace[968465957] 'process raft request' (duration: 136.846601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:35.400215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.584816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T08:13:35.400921Z","caller":"traceutil/trace.go:171","msg":"trace[2062953558] linearizableReadLoop","detail":"{readStateIndex:25973; appliedIndex:25971; }","duration":"184.80243ms","start":"2026-07-01T08:13:35.215896Z","end":"2026-07-01T08:13:35.400698Z","steps":["trace[2062953558] 'read index received' (duration: 12.395811ms)","trace[2062953558] 'applied index is now lower than readState.Index' (duration: 172.404659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:35.401347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.449086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-01T08:13:35.40139Z","caller":"traceutil/trace.go:171","msg":"trace[908340320] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25152; }","duration":"185.503778ms","start":"2026-07-01T08:13:35.215872Z","end":"2026-07-01T08:13:35.401376Z","steps":["trace[908340320] 'agreement among raft nodes before linearized reading' (duration: 185.103218ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:36.27453Z","caller":"traceutil/trace.go:171","msg":"trace[2023093800] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"227.064782ms","start":"2026-07-01T08:13:36.047425Z","end":"2026-07-01T08:13:36.27449Z","steps":["trace[2023093800] 'process raft request' (duration: 127.140045ms)","trace[2023093800] 'compare' (duration: 99.65004ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:36.274775Z","caller":"traceutil/trace.go:171","msg":"trace[545946408] linearizableReadLoop","detail":"{readStateIndex:25979; appliedIndex:25976; }","duration":"186.736256ms","start":"2026-07-01T08:13:36.088003Z","end":"2026-07-01T08:13:36.274739Z","steps":["trace[545946408] 'read index received' (duration: 22.407012ms)","trace[545946408] 'applied index is now lower than readState.Index' (duration: 164.327814ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:36.274906Z","caller":"traceutil/trace.go:171","msg":"trace[708789649] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"226.489787ms","start":"2026-07-01T08:13:36.04839Z","end":"2026-07-01T08:13:36.274879Z","steps":["trace[708789649] 'process raft request' (duration: 226.033976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:36.27499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.958462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:36.275027Z","caller":"traceutil/trace.go:171","msg":"trace[1246987669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25158; }","duration":"187.047534ms","start":"2026-07-01T08:13:36.087969Z","end":"2026-07-01T08:13:36.275016Z","steps":["trace[1246987669] 'agreement among raft nodes before linearized reading' (duration: 186.933431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:36.527583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.880299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:36.527795Z","caller":"traceutil/trace.go:171","msg":"trace[1461495728] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25158; }","duration":"122.149475ms","start":"2026-07-01T08:13:36.405619Z","end":"2026-07-01T08:13:36.527769Z","steps":["trace[1461495728] 'count revisions from in-memory index tree' (duration: 121.779687ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:36.71377Z","caller":"traceutil/trace.go:171","msg":"trace[433873223] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"180.005114ms","start":"2026-07-01T08:13:36.533717Z","end":"2026-07-01T08:13:36.713722Z","steps":["trace[433873223] 'process raft request' (duration: 179.771519ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:36.836745Z","caller":"traceutil/trace.go:171","msg":"trace[1965755599] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"157.015918ms","start":"2026-07-01T08:13:36.679696Z","end":"2026-07-01T08:13:36.836712Z","steps":["trace[1965755599] 'process raft request' (duration: 156.869455ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:36.836724Z","caller":"traceutil/trace.go:171","msg":"trace[1289334570] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"157.113801ms","start":"2026-07-01T08:13:36.679544Z","end":"2026-07-01T08:13:36.836658Z","steps":["trace[1289334570] 'process raft request' (duration: 72.921185ms)","trace[1289334570] 'compare' (duration: 83.806797ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:37.067219Z","caller":"traceutil/trace.go:171","msg":"trace[1270153257] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"118.984087ms","start":"2026-07-01T08:13:36.948198Z","end":"2026-07-01T08:13:37.067182Z","steps":["trace[1270153257] 'process raft request' (duration: 82.666279ms)","trace[1270153257] 'compare' (duration: 35.992581ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:37.316926Z","caller":"traceutil/trace.go:171","msg":"trace[1910914181] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"163.487305ms","start":"2026-07-01T08:13:37.153412Z","end":"2026-07-01T08:13:37.316899Z","steps":["trace[1910914181] 'process raft request' (duration: 163.28567ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:37.445258Z","caller":"traceutil/trace.go:171","msg":"trace[231661236] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"291.010669ms","start":"2026-07-01T08:13:37.154204Z","end":"2026-07-01T08:13:37.445215Z","steps":["trace[231661236] 'process raft request' (duration: 288.316344ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:37.445313Z","caller":"traceutil/trace.go:171","msg":"trace[789472377] linearizableReadLoop","detail":"{readStateIndex:25986; appliedIndex:25984; }","duration":"209.803095ms","start":"2026-07-01T08:13:37.235474Z","end":"2026-07-01T08:13:37.445277Z","steps":["trace[789472377] 'read index received' (duration: 81.206344ms)","trace[789472377] 'applied index is now lower than readState.Index' (duration: 128.592311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:37.445564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.066281ms","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-07-01T08:13:37.445627Z","caller":"traceutil/trace.go:171","msg":"trace[758901485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25166; }","duration":"210.170734ms","start":"2026-07-01T08:13:37.235427Z","end":"2026-07-01T08:13:37.445597Z","steps":["trace[758901485] 'agreement among raft nodes before linearized reading' (duration: 209.961599ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:37.655174Z","caller":"traceutil/trace.go:171","msg":"trace[232919236] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"134.052043ms","start":"2026-07-01T08:13:37.521079Z","end":"2026-07-01T08:13:37.655131Z","steps":["trace[232919236] 'process raft request' (duration: 67.967795ms)","trace[232919236] 'compare' (duration: 65.690718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:38.025019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.931313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:38.025108Z","caller":"traceutil/trace.go:171","msg":"trace[1175406719] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25168; }","duration":"185.047366ms","start":"2026-07-01T08:13:37.84004Z","end":"2026-07-01T08:13:38.025088Z","steps":["trace[1175406719] 'count revisions from in-memory index tree' (duration: 184.772909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:38.025469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.01602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:38.025528Z","caller":"traceutil/trace.go:171","msg":"trace[2079031750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25168; }","duration":"203.123362ms","start":"2026-07-01T08:13:37.82239Z","end":"2026-07-01T08:13:38.025514Z","steps":["trace[2079031750] 'count revisions from in-memory index tree' (duration: 202.915247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:38.02549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.113843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:38.025709Z","caller":"traceutil/trace.go:171","msg":"trace[1009378592] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25168; }","duration":"196.353239ms","start":"2026-07-01T08:13:37.829347Z","end":"2026-07-01T08:13:38.0257Z","steps":["trace[1009378592] 'count revisions from in-memory index tree' (duration: 196.00314ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:38.197645Z","caller":"traceutil/trace.go:171","msg":"trace[337014887] linearizableReadLoop","detail":"{readStateIndex:25990; appliedIndex:25989; }","duration":"111.18244ms","start":"2026-07-01T08:13:38.086437Z","end":"2026-07-01T08:13:38.197619Z","steps":["trace[337014887] 'read index received' (duration: 110.911734ms)","trace[337014887] 'applied index is now lower than readState.Index' (duration: 268.296µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:38.197689Z","caller":"traceutil/trace.go:171","msg":"trace[1224332434] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"124.238136ms","start":"2026-07-01T08:13:38.073431Z","end":"2026-07-01T08:13:38.197669Z","steps":["trace[1224332434] 'process raft request' (duration: 123.867867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:38.197819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.361894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:38.197899Z","caller":"traceutil/trace.go:171","msg":"trace[1337874000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25169; }","duration":"111.510737ms","start":"2026-07-01T08:13:38.086369Z","end":"2026-07-01T08:13:38.19788Z","steps":["trace[1337874000] 'agreement among raft nodes before linearized reading' (duration: 111.362124ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:38.281092Z","caller":"traceutil/trace.go:171","msg":"trace[1511711892] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"164.082149ms","start":"2026-07-01T08:13:38.116972Z","end":"2026-07-01T08:13:38.281054Z","steps":["trace[1511711892] 'process raft request' (duration: 137.627949ms)","trace[1511711892] 'compare' (duration: 26.089601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:38.281318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.174957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-07-01T08:13:38.281414Z","caller":"traceutil/trace.go:171","msg":"trace[1517214850] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25170; }","duration":"151.407062ms","start":"2026-07-01T08:13:38.129985Z","end":"2026-07-01T08:13:38.281392Z","steps":["trace[1517214850] 'agreement among raft nodes before linearized reading' (duration: 151.095785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:38.281975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.262914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129634"} {"level":"info","ts":"2026-07-01T08:13:38.282056Z","caller":"traceutil/trace.go:171","msg":"trace[2140158434] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25170; }","duration":"133.410228ms","start":"2026-07-01T08:13:38.148624Z","end":"2026-07-01T08:13:38.282035Z","steps":["trace[2140158434] 'agreement among raft nodes before linearized reading' (duration: 132.598788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:38.338071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.611404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:13:38.338151Z","caller":"traceutil/trace.go:171","msg":"trace[73531183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25171; }","duration":"138.750927ms","start":"2026-07-01T08:13:38.199381Z","end":"2026-07-01T08:13:38.338132Z","steps":["trace[73531183] 'agreement among raft nodes before linearized reading' (duration: 138.593963ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:39.712243Z","caller":"traceutil/trace.go:171","msg":"trace[1031997565] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"107.481289ms","start":"2026-07-01T08:13:39.604733Z","end":"2026-07-01T08:13:39.712215Z","steps":["trace[1031997565] 'process raft request' (duration: 106.909766ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:14:10.793912Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ec.wal"} {"level":"info","ts":"2026-07-01T08:15:36.832527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23507} {"level":"warn","ts":"2026-07-01T08:15:37.324511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.103251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-01T08:15:37.324588Z","caller":"traceutil/trace.go:171","msg":"trace[1156215289] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26357; }","duration":"167.241705ms","start":"2026-07-01T08:15:37.157328Z","end":"2026-07-01T08:15:37.32457Z","steps":["trace[1156215289] 'range keys from in-memory index tree' (duration: 166.896046ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:37.532801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23507,"took":"691.67239ms","hash":3113647570,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":36794368,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-01T08:15:37.532925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3113647570,"revision":23507,"compact-revision":20220} {"level":"info","ts":"2026-07-01T08:15:43.043076Z","caller":"traceutil/trace.go:171","msg":"trace[2048599607] linearizableReadLoop","detail":"{readStateIndex:27250; appliedIndex:27249; }","duration":"102.770556ms","start":"2026-07-01T08:15:42.940284Z","end":"2026-07-01T08:15:43.043055Z","steps":["trace[2048599607] 'read index received' (duration: 55.911772ms)","trace[2048599607] 'applied index is now lower than readState.Index' (duration: 46.858044ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:15:43.04327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.972101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:15:43.043305Z","caller":"traceutil/trace.go:171","msg":"trace[247043177] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26400; }","duration":"103.063043ms","start":"2026-07-01T08:15:42.94023Z","end":"2026-07-01T08:15:43.043293Z","steps":["trace[247043177] 'agreement among raft nodes before linearized reading' (duration: 102.918739ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:43.043592Z","caller":"traceutil/trace.go:171","msg":"trace[1092730574] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"180.411405ms","start":"2026-07-01T08:15:42.863166Z","end":"2026-07-01T08:15:43.043577Z","steps":["trace[1092730574] 'process raft request' (duration: 133.070649ms)","trace[1092730574] 'compare' (duration: 46.68644ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:43.044212Z","caller":"traceutil/trace.go:171","msg":"trace[1037997874] transaction","detail":"{read_only:false; response_revision:26402; number_of_response:1; }","duration":"103.557745ms","start":"2026-07-01T08:15:42.940625Z","end":"2026-07-01T08:15:43.044182Z","steps":["trace[1037997874] 'process raft request' (duration: 103.465963ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:43.044366Z","caller":"traceutil/trace.go:171","msg":"trace[1556421761] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"103.815451ms","start":"2026-07-01T08:15:42.940528Z","end":"2026-07-01T08:15:43.044343Z","steps":["trace[1556421761] 'process raft request' (duration: 103.488663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:06.874612Z","caller":"traceutil/trace.go:171","msg":"trace[523362441] transaction","detail":"{read_only:false; response_revision:27245; number_of_response:1; }","duration":"127.238608ms","start":"2026-07-01T08:17:06.747345Z","end":"2026-07-01T08:17:06.874584Z","steps":["trace[523362441] 'process raft request' (duration: 127.084914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:20:36.840558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26354} {"level":"info","ts":"2026-07-01T08:20:36.983897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26354,"took":"138.451219ms","hash":2890349387,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":33619968,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T08:20:36.983975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2890349387,"revision":26354,"compact-revision":23507} {"level":"info","ts":"2026-07-01T08:20:54.694976Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T08:20:54.700304Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T08:20:54.700412Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T08:25:36.851909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28951} {"level":"info","ts":"2026-07-01T08:25:36.989767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28951,"took":"132.971037ms","hash":3106178603,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":30236672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T08:25:36.989837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3106178603,"revision":28951,"compact-revision":26354} {"level":"info","ts":"2026-07-01T08:30:36.859959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31165} {"level":"info","ts":"2026-07-01T08:30:36.985384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31165,"took":"119.326832ms","hash":807082626,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:30:36.985465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":807082626,"revision":31165,"compact-revision":28951} {"level":"info","ts":"2026-07-01T08:35:36.866128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33433} {"level":"info","ts":"2026-07-01T08:35:37.026177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33433,"took":"154.391762ms","hash":557483550,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31940608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T08:35:37.026259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":557483550,"revision":33433,"compact-revision":31165} {"level":"info","ts":"2026-07-01T08:40:36.875087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35727} {"level":"info","ts":"2026-07-01T08:40:37.026103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35727,"took":"143.254291ms","hash":998764838,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31604736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T08:40:37.026294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":998764838,"revision":35727,"compact-revision":33433} {"level":"info","ts":"2026-07-01T08:42:24.29178Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T08:42:24.29845Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T08:42:24.298724Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-01T08:45:36.881946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37956} {"level":"info","ts":"2026-07-01T08:45:37.009934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37956,"took":"124.262248ms","hash":3137198211,"current-db-size-bytes":58724352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:45:37.010003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3137198211,"revision":37956,"compact-revision":35727}