{"level":"warn","ts":"2026-08-22T14:41:24.010274Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:41:24.010379Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-08-22T14:41:24.010469Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:41:24.010484Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-22T14:41:24.010502Z","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-08-22T14:41:24.011107Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-08-22T14:41:24.011186Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-08-22T14:41:24.014925Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.541239ms"} {"level":"info","ts":"2026-08-22T14:41:24.018635Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-08-22T14:41:24.018707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:41:24.01873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-08-22T14:41:24.01874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:41:24.018752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-08-22T14:41:24.01879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-08-22T14:41:24.02313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:41:24.02436Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:41:24.025387Z","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-08-22T14:41:24.028265Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:41:24.02846Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:41:24.028534Z","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-08-22T14:41:24.028643Z","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-08-22T14:41:24.028657Z","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-08-22T14:41:24.030175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-08-22T14:41:24.030363Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-22T14:41:24.032719Z","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-08-22T14:41:24.0328Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-22T14:41:24.032844Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-22T14:41:24.033138Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:41:24.033226Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:41:24.219223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:41:24.219296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:41:24.219342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-08-22T14:41:24.219365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:41:24.219377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-22T14:41:24.2194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-08-22T14:41:24.219413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-22T14:41:24.220377Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:41:24.221269Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:41:24.221313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:41:24.221282Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:41:24.221889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:41:24.221927Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:41:24.2222Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:41:24.222393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:41:24.222438Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:41:24.225023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:41:24.231541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-08-22T14:42:12.974839Z","caller":"traceutil/trace.go:171","msg":"trace[1418114446] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"125.651217ms","start":"2026-08-22T14:42:12.849162Z","end":"2026-08-22T14:42:12.974814Z","steps":["trace[1418114446] 'process raft request' (duration: 125.455752ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:13.305658Z","caller":"traceutil/trace.go:171","msg":"trace[35583875] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"239.823986ms","start":"2026-08-22T14:42:13.065815Z","end":"2026-08-22T14:42:13.305639Z","steps":["trace[35583875] 'read index received' (duration: 239.668771ms)","trace[35583875] 'applied index is now lower than readState.Index' (duration: 154.365µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:13.30571Z","caller":"traceutil/trace.go:171","msg":"trace[86390374] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"265.534847ms","start":"2026-08-22T14:42:13.040155Z","end":"2026-08-22T14:42:13.30569Z","steps":["trace[86390374] 'process raft request' (duration: 265.315101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:42:13.305829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.005581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d565z\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-22T14:42:13.305902Z","caller":"traceutil/trace.go:171","msg":"trace[401723752] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d565z; range_end:; response_count:1; response_revision:614; }","duration":"240.139235ms","start":"2026-08-22T14:42:13.065755Z","end":"2026-08-22T14:42:13.305894Z","steps":["trace[401723752] 'agreement among raft nodes before linearized reading' (duration: 239.97211ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:13.497866Z","caller":"traceutil/trace.go:171","msg":"trace[1036111716] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"176.983517ms","start":"2026-08-22T14:42:13.320829Z","end":"2026-08-22T14:42:13.497812Z","steps":["trace[1036111716] 'process raft request' (duration: 176.796111ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:28.389268Z","caller":"traceutil/trace.go:171","msg":"trace[893683298] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"195.112739ms","start":"2026-08-22T14:42:28.194109Z","end":"2026-08-22T14:42:28.389222Z","steps":["trace[893683298] 'process raft request' (duration: 194.833321ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:39.898894Z","caller":"traceutil/trace.go:171","msg":"trace[1084059095] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"391.930124ms","start":"2026-08-22T14:43:39.506932Z","end":"2026-08-22T14:43:39.898863Z","steps":["trace[1084059095] 'process raft request' (duration: 328.011284ms)","trace[1084059095] 'compare' (duration: 63.677624ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:39.898862Z","caller":"traceutil/trace.go:171","msg":"trace[756540136] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1876; }","duration":"381.867915ms","start":"2026-08-22T14:43:39.516954Z","end":"2026-08-22T14:43:39.898822Z","steps":["trace[756540136] 'read index received' (duration: 17.492188ms)","trace[756540136] 'applied index is now lower than readState.Index' (duration: 364.369717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:43:39.899258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.271116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e0194b14-fb91-4b8f-afc6-1860228915a2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-22T14:43:39.899357Z","caller":"traceutil/trace.go:171","msg":"trace[549930467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e0194b14-fb91-4b8f-afc6-1860228915a2; range_end:; response_count:1; response_revision:1806; }","duration":"382.43643ms","start":"2026-08-22T14:43:39.516899Z","end":"2026-08-22T14:43:39.899335Z","steps":["trace[549930467] 'agreement among raft nodes before linearized reading' (duration: 382.107891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:39.899406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:39.516868Z","time spent":"382.528943ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e0194b14-fb91-4b8f-afc6-1860228915a2\" "} {"level":"warn","ts":"2026-08-22T14:43:39.899761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:39.506915Z","time spent":"392.092829ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:43:39.901348Z","caller":"traceutil/trace.go:171","msg":"trace[1748623179] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"116.117124ms","start":"2026-08-22T14:43:39.785211Z","end":"2026-08-22T14:43:39.901329Z","steps":["trace[1748623179] 'process raft request' (duration: 115.895447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:39.901439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.575257ms","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-08-22T14:43:39.901511Z","caller":"traceutil/trace.go:171","msg":"trace[1662742490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1807; }","duration":"162.694481ms","start":"2026-08-22T14:43:39.738797Z","end":"2026-08-22T14:43:39.901491Z","steps":["trace[1662742490] 'agreement among raft nodes before linearized reading' (duration: 162.468204ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:40.252141Z","caller":"traceutil/trace.go:171","msg":"trace[145080121] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1882; }","duration":"250.495416ms","start":"2026-08-22T14:43:40.001586Z","end":"2026-08-22T14:43:40.252081Z","steps":["trace[145080121] 'read index received' (duration: 162.835934ms)","trace[145080121] 'applied index is now lower than readState.Index' (duration: 87.657502ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:40.252188Z","caller":"traceutil/trace.go:171","msg":"trace[502415181] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"257.957654ms","start":"2026-08-22T14:43:39.994182Z","end":"2026-08-22T14:43:40.25214Z","steps":["trace[502415181] 'process raft request' (duration: 170.313632ms)","trace[502415181] 'compare' (duration: 87.010794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:43:40.252428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.803635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:43:40.252477Z","caller":"traceutil/trace.go:171","msg":"trace[912364408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1811; }","duration":"250.914027ms","start":"2026-08-22T14:43:40.00155Z","end":"2026-08-22T14:43:40.252464Z","steps":["trace[912364408] 'agreement among raft nodes before linearized reading' (duration: 250.770954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:40.252644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.981839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e0194b14-fb91-4b8f-afc6-1860228915a2\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-22T14:43:40.253121Z","caller":"traceutil/trace.go:171","msg":"trace[2054348538] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e0194b14-fb91-4b8f-afc6-1860228915a2; range_end:; response_count:1; response_revision:1811; }","duration":"158.530935ms","start":"2026-08-22T14:43:40.094559Z","end":"2026-08-22T14:43:40.25309Z","steps":["trace[2054348538] 'agreement among raft nodes before linearized reading' (duration: 157.799895ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:30.515788Z","caller":"traceutil/trace.go:171","msg":"trace[1413459881] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"276.067748ms","start":"2026-08-22T14:44:30.239681Z","end":"2026-08-22T14:44:30.515749Z","steps":["trace[1413459881] 'process raft request' (duration: 228.593235ms)","trace[1413459881] 'compare' (duration: 47.337269ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:44:30.521621Z","caller":"traceutil/trace.go:171","msg":"trace[555137928] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"207.333813ms","start":"2026-08-22T14:44:30.314238Z","end":"2026-08-22T14:44:30.521572Z","steps":["trace[555137928] 'process raft request' (duration: 206.961593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:38.808072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.070016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e096f58e-7e7d-4933-8697-830a340228a9\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-08-22T14:49:38.808159Z","caller":"traceutil/trace.go:171","msg":"trace[1713908976] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e096f58e-7e7d-4933-8697-830a340228a9; range_end:; response_count:1; response_revision:4714; }","duration":"108.19149ms","start":"2026-08-22T14:49:38.699943Z","end":"2026-08-22T14:49:38.808135Z","steps":["trace[1713908976] 'range keys from in-memory index tree' (duration: 107.925633ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:38.956146Z","caller":"traceutil/trace.go:171","msg":"trace[1152755344] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"100.163597ms","start":"2026-08-22T14:49:38.855938Z","end":"2026-08-22T14:49:38.956101Z","steps":["trace[1152755344] 'process raft request' (duration: 99.917181ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:44.888021Z","caller":"traceutil/trace.go:171","msg":"trace[1840346022] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"113.094346ms","start":"2026-08-22T14:49:44.774855Z","end":"2026-08-22T14:49:44.88795Z","steps":["trace[1840346022] 'read index received' (duration: 112.949002ms)","trace[1840346022] 'applied index is now lower than readState.Index' (duration: 143.694µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:49:44.888015Z","caller":"traceutil/trace.go:171","msg":"trace[1413900361] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4921; }","duration":"164.595388ms","start":"2026-08-22T14:49:44.723378Z","end":"2026-08-22T14:49:44.887973Z","steps":["trace[1413900361] 'process raft request' (duration: 164.411083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:44.888357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.432605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-g7w5p-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-08-22T14:49:44.88849Z","caller":"traceutil/trace.go:171","msg":"trace[1426207487] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-g7w5p-tls; range_end:; response_count:1; response_revision:4921; }","duration":"113.686323ms","start":"2026-08-22T14:49:44.774774Z","end":"2026-08-22T14:49:44.88846Z","steps":["trace[1426207487] 'agreement among raft nodes before linearized reading' (duration: 113.280971ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:45.222439Z","caller":"traceutil/trace.go:171","msg":"trace[690657621] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"171.661946ms","start":"2026-08-22T14:49:45.050751Z","end":"2026-08-22T14:49:45.222413Z","steps":["trace[690657621] 'process raft request' (duration: 171.508492ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:45.222648Z","caller":"traceutil/trace.go:171","msg":"trace[143345672] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"172.639113ms","start":"2026-08-22T14:49:45.049998Z","end":"2026-08-22T14:49:45.222637Z","steps":["trace[143345672] 'process raft request' (duration: 131.832657ms)","trace[143345672] 'compare' (duration: 40.303182ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:49:45.222824Z","caller":"traceutil/trace.go:171","msg":"trace[1621349696] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"168.941969ms","start":"2026-08-22T14:49:45.053813Z","end":"2026-08-22T14:49:45.222755Z","steps":["trace[1621349696] 'process raft request' (duration: 168.522158ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:52.364294Z","caller":"traceutil/trace.go:171","msg":"trace[68866519] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"118.117946ms","start":"2026-08-22T14:49:52.246141Z","end":"2026-08-22T14:49:52.364258Z","steps":["trace[68866519] 'process raft request' (duration: 99.599941ms)","trace[68866519] 'compare' (duration: 18.301929ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:49:58.308136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.663345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:49:58.308449Z","caller":"traceutil/trace.go:171","msg":"trace[525710876] linearizableReadLoop","detail":"{readStateIndex:5386; appliedIndex:5385; }","duration":"161.430111ms","start":"2026-08-22T14:49:58.146983Z","end":"2026-08-22T14:49:58.308413Z","steps":["trace[525710876] 'read index received' (duration: 25.249772ms)","trace[525710876] 'applied index is now lower than readState.Index' (duration: 136.178929ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:49:58.308493Z","caller":"traceutil/trace.go:171","msg":"trace[1414787603] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"275.549234ms","start":"2026-08-22T14:49:58.03289Z","end":"2026-08-22T14:49:58.30844Z","steps":["trace[1414787603] 'process raft request' (duration: 139.360036ms)","trace[1414787603] 'compare' (duration: 135.15917ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:49:58.308677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.690948ms","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-08-22T14:49:58.308706Z","caller":"traceutil/trace.go:171","msg":"trace[1933676697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5188; }","duration":"161.76968ms","start":"2026-08-22T14:49:58.146928Z","end":"2026-08-22T14:49:58.308698Z","steps":["trace[1933676697] 'agreement among raft nodes before linearized reading' (duration: 161.581515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:58.309026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.25626ms","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-08-22T14:49:58.309042Z","caller":"traceutil/trace.go:171","msg":"trace[1498009239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5188; }","duration":"159.314342ms","start":"2026-08-22T14:49:58.149723Z","end":"2026-08-22T14:49:58.309038Z","steps":["trace[1498009239] 'agreement among raft nodes before linearized reading' (duration: 159.25889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.11689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.948407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:50:06.117092Z","caller":"traceutil/trace.go:171","msg":"trace[849552695] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5585; }","duration":"157.829688ms","start":"2026-08-22T14:50:05.959239Z","end":"2026-08-22T14:50:06.117068Z","steps":["trace[849552695] 'read index received' (duration: 3.852897ms)","trace[849552695] 'applied index is now lower than readState.Index' (duration: 153.975301ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:06.117124Z","caller":"traceutil/trace.go:171","msg":"trace[1333478975] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5387; }","duration":"157.964442ms","start":"2026-08-22T14:50:05.959132Z","end":"2026-08-22T14:50:06.117096Z","steps":["trace[1333478975] 'process raft request' (duration: 157.868549ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:06.117106Z","caller":"traceutil/trace.go:171","msg":"trace[478841364] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"158.235849ms","start":"2026-08-22T14:50:05.958838Z","end":"2026-08-22T14:50:06.117073Z","steps":["trace[478841364] 'process raft request' (duration: 30.995704ms)","trace[478841364] 'compare' (duration: 126.510655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:06.117256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.540351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:50:06.117303Z","caller":"traceutil/trace.go:171","msg":"trace[928009996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5387; }","duration":"115.637093ms","start":"2026-08-22T14:50:06.001649Z","end":"2026-08-22T14:50:06.117287Z","steps":["trace[928009996] 'agreement among raft nodes before linearized reading' (duration: 115.56047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.117406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.136237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-22T14:50:06.117521Z","caller":"traceutil/trace.go:171","msg":"trace[931226856] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5387; }","duration":"158.289512ms","start":"2026-08-22T14:50:05.959209Z","end":"2026-08-22T14:50:06.117499Z","steps":["trace[931226856] 'agreement among raft nodes before linearized reading' (duration: 157.928941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.638941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.87725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T14:50:06.639268Z","caller":"traceutil/trace.go:171","msg":"trace[647440146] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"297.377437ms","start":"2026-08-22T14:50:06.341868Z","end":"2026-08-22T14:50:06.639246Z","steps":["trace[647440146] 'process raft request' (duration: 297.306875ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:06.639459Z","caller":"traceutil/trace.go:171","msg":"trace[549696206] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5389; }","duration":"515.851095ms","start":"2026-08-22T14:50:06.123596Z","end":"2026-08-22T14:50:06.639447Z","steps":["trace[549696206] 'process raft request' (duration: 127.293627ms)","trace[549696206] 'compare' (duration: 387.569881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:06.639517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.123586Z","time spent":"515.901157ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":88,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-08-22T14:50:06.639555Z","caller":"traceutil/trace.go:171","msg":"trace[1655830361] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"508.223853ms","start":"2026-08-22T14:50:06.131294Z","end":"2026-08-22T14:50:06.639518Z","steps":["trace[1655830361] 'process raft request' (duration: 507.767841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.639674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.131283Z","time spent":"508.325116ms","remote":"127.0.0.1:45464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:50:06.639969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.239476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-22T14:50:06.64011Z","caller":"traceutil/trace.go:171","msg":"trace[2079635310] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5391; }","duration":"516.420861ms","start":"2026-08-22T14:50:06.123642Z","end":"2026-08-22T14:50:06.640063Z","steps":["trace[2079635310] 'agreement among raft nodes before linearized reading' (duration: 516.01331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.640163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.123627Z","time spent":"516.526435ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":673,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"info","ts":"2026-08-22T14:50:06.639294Z","caller":"traceutil/trace.go:171","msg":"trace[546194679] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5589; }","duration":"482.700571ms","start":"2026-08-22T14:50:06.156554Z","end":"2026-08-22T14:50:06.639255Z","steps":["trace[546194679] 'read index received' (duration: 94.375469ms)","trace[546194679] 'applied index is now lower than readState.Index' (duration: 388.321422ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:06.640509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.830495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2428"} {"level":"info","ts":"2026-08-22T14:50:06.640565Z","caller":"traceutil/trace.go:171","msg":"trace[1541142234] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5391; }","duration":"473.941128ms","start":"2026-08-22T14:50:06.166604Z","end":"2026-08-22T14:50:06.640545Z","steps":["trace[1541142234] 'agreement among raft nodes before linearized reading' (duration: 473.795814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.640646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.166597Z","time spent":"474.001399ms","remote":"127.0.0.1:45464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2451,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-08-22T14:50:06.640735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.126214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-22T14:50:06.640726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.370677ms","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-08-22T14:50:06.640772Z","caller":"traceutil/trace.go:171","msg":"trace[1732099265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5391; }","duration":"244.212696ms","start":"2026-08-22T14:50:06.39655Z","end":"2026-08-22T14:50:06.640763Z","steps":["trace[1732099265] 'agreement among raft nodes before linearized reading' (duration: 244.098842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.640772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.243971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"warn","ts":"2026-08-22T14:50:06.640787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.961016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:50:06.640822Z","caller":"traceutil/trace.go:171","msg":"trace[49974625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5391; }","duration":"241.513961ms","start":"2026-08-22T14:50:06.399284Z","end":"2026-08-22T14:50:06.640798Z","steps":["trace[49974625] 'agreement among raft nodes before linearized reading' (duration: 241.12775ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:06.640869Z","caller":"traceutil/trace.go:171","msg":"trace[960748581] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5391; }","duration":"461.063509ms","start":"2026-08-22T14:50:06.179787Z","end":"2026-08-22T14:50:06.640851Z","steps":["trace[960748581] 'agreement among raft nodes before linearized reading' (duration: 460.932985ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:06.64087Z","caller":"traceutil/trace.go:171","msg":"trace[581009114] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5391; }","duration":"500.380485ms","start":"2026-08-22T14:50:06.14046Z","end":"2026-08-22T14:50:06.64084Z","steps":["trace[581009114] 'agreement among raft nodes before linearized reading' (duration: 498.997837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.640913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.179779Z","time spent":"461.12622ms","remote":"127.0.0.1:55252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-22T14:50:06.640931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.14044Z","time spent":"500.474347ms","remote":"127.0.0.1:54872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225424,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-08-22T14:50:06.642408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.124559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31d39a5c-1bcf-4e57-9783-3d15ad0efaea\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-22T14:50:06.642542Z","caller":"traceutil/trace.go:171","msg":"trace[1849725951] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31d39a5c-1bcf-4e57-9783-3d15ad0efaea; range_end:; response_count:1; response_revision:5391; }","duration":"504.243723ms","start":"2026-08-22T14:50:06.138235Z","end":"2026-08-22T14:50:06.642479Z","steps":["trace[1849725951] 'agreement among raft nodes before linearized reading' (duration: 501.2886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:06.642622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:50:06.138224Z","time spent":"504.361116ms","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31d39a5c-1bcf-4e57-9783-3d15ad0efaea\" "} {"level":"info","ts":"2026-08-22T14:51:24.353025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2947} {"level":"info","ts":"2026-08-22T14:51:24.435195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2947,"took":"78.630094ms","hash":3339785732,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":34000896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T14:51:24.435264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339785732,"revision":2947,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:56:17.302939Z","caller":"traceutil/trace.go:171","msg":"trace[590405872] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"116.798785ms","start":"2026-08-22T14:56:17.186078Z","end":"2026-08-22T14:56:17.302876Z","steps":["trace[590405872] 'process raft request' (duration: 116.567579ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:56:24.360168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6058} {"level":"info","ts":"2026-08-22T14:56:24.454309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6058,"took":"91.980143ms","hash":481054914,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":23736320,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-22T14:56:24.454381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":481054914,"revision":6058,"compact-revision":2947} {"level":"info","ts":"2026-08-22T14:56:29.223075Z","caller":"traceutil/trace.go:171","msg":"trace[512673265] linearizableReadLoop","detail":"{readStateIndex:8570; appliedIndex:8567; }","duration":"220.979108ms","start":"2026-08-22T14:56:29.002069Z","end":"2026-08-22T14:56:29.223048Z","steps":["trace[512673265] 'read index received' (duration: 38.913955ms)","trace[512673265] 'applied index is now lower than readState.Index' (duration: 182.064163ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:56:29.223166Z","caller":"traceutil/trace.go:171","msg":"trace[1114288489] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"272.421341ms","start":"2026-08-22T14:56:28.950703Z","end":"2026-08-22T14:56:29.223125Z","steps":["trace[1114288489] 'process raft request' (duration: 272.273937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:56:29.223226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.141092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:56:29.223274Z","caller":"traceutil/trace.go:171","msg":"trace[799232912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8275; }","duration":"221.252495ms","start":"2026-08-22T14:56:29.00201Z","end":"2026-08-22T14:56:29.223263Z","steps":["trace[799232912] 'agreement among raft nodes before linearized reading' (duration: 221.143202ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:56:29.223182Z","caller":"traceutil/trace.go:171","msg":"trace[1974158256] transaction","detail":"{read_only:false; response_revision:8274; number_of_response:1; }","duration":"272.584336ms","start":"2026-08-22T14:56:28.950565Z","end":"2026-08-22T14:56:29.223149Z","steps":["trace[1974158256] 'process raft request' (duration: 176.963342ms)","trace[1974158256] 'compare' (duration: 95.296195ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:56:57.769212Z","caller":"traceutil/trace.go:171","msg":"trace[1123980707] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"239.783951ms","start":"2026-08-22T14:56:57.529403Z","end":"2026-08-22T14:56:57.769187Z","steps":["trace[1123980707] 'process raft request' (duration: 239.607486ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:56:57.76981Z","caller":"traceutil/trace.go:171","msg":"trace[1069738989] linearizableReadLoop","detail":"{readStateIndex:8785; appliedIndex:8785; }","duration":"235.11625ms","start":"2026-08-22T14:56:57.534682Z","end":"2026-08-22T14:56:57.769798Z","steps":["trace[1069738989] 'read index received' (duration: 235.11009ms)","trace[1069738989] 'applied index is now lower than readState.Index' (duration: 5.17µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:56:57.770074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.204195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T14:56:57.77011Z","caller":"traceutil/trace.go:171","msg":"trace[333766723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8482; }","duration":"132.295417ms","start":"2026-08-22T14:56:57.637806Z","end":"2026-08-22T14:56:57.770101Z","steps":["trace[333766723] 'agreement among raft nodes before linearized reading' (duration: 132.07639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:56:57.770348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.961197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T14:56:57.770377Z","caller":"traceutil/trace.go:171","msg":"trace[45674207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8482; }","duration":"118.011038ms","start":"2026-08-22T14:56:57.652357Z","end":"2026-08-22T14:56:57.770368Z","steps":["trace[45674207] 'agreement among raft nodes before linearized reading' (duration: 117.921755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:56:57.77045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.735999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mjbdq\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-22T14:56:57.770533Z","caller":"traceutil/trace.go:171","msg":"trace[835316554] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mjbdq; range_end:; response_count:1; response_revision:8482; }","duration":"235.855562ms","start":"2026-08-22T14:56:57.534657Z","end":"2026-08-22T14:56:57.770512Z","steps":["trace[835316554] 'agreement among raft nodes before linearized reading' (duration: 235.600075ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:56:57.986974Z","caller":"traceutil/trace.go:171","msg":"trace[205175454] linearizableReadLoop","detail":"{readStateIndex:8787; appliedIndex:8785; }","duration":"209.867119ms","start":"2026-08-22T14:56:57.777085Z","end":"2026-08-22T14:56:57.986952Z","steps":["trace[205175454] 'read index received' (duration: 192.665199ms)","trace[205175454] 'applied index is now lower than readState.Index' (duration: 17.201ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:56:57.987102Z","caller":"traceutil/trace.go:171","msg":"trace[1099501475] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"211.351209ms","start":"2026-08-22T14:56:57.775739Z","end":"2026-08-22T14:56:57.98709Z","steps":["trace[1099501475] 'process raft request' (duration: 211.123763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:56:57.987089Z","caller":"traceutil/trace.go:171","msg":"trace[343457471] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"211.991048ms","start":"2026-08-22T14:56:57.77507Z","end":"2026-08-22T14:56:57.987061Z","steps":["trace[343457471] 'process raft request' (duration: 194.408108ms)","trace[343457471] 'compare' (duration: 16.706455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:56:57.987344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.061327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:56:57.987562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.845022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:56:57.987608Z","caller":"traceutil/trace.go:171","msg":"trace[863444398] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8484; }","duration":"113.930474ms","start":"2026-08-22T14:56:57.873666Z","end":"2026-08-22T14:56:57.987596Z","steps":["trace[863444398] 'agreement among raft nodes before linearized reading' (duration: 113.852082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:56:57.987807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.701822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228544"} {"level":"info","ts":"2026-08-22T14:56:57.987856Z","caller":"traceutil/trace.go:171","msg":"trace[1649238752] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8484; }","duration":"210.776073ms","start":"2026-08-22T14:56:57.77707Z","end":"2026-08-22T14:56:57.987846Z","steps":["trace[1649238752] 'agreement among raft nodes before linearized reading' (duration: 210.417663ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:56:57.98741Z","caller":"traceutil/trace.go:171","msg":"trace[1773844647] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8484; }","duration":"152.17072ms","start":"2026-08-22T14:56:57.835225Z","end":"2026-08-22T14:56:57.987396Z","steps":["trace[1773844647] 'agreement among raft nodes before linearized reading' (duration: 152.053397ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:57:33.10941Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237e.wal"} {"level":"info","ts":"2026-08-22T14:59:12.560794Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T14:59:12.56548Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T14:59:12.565559Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T15:01:24.367081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8249} {"level":"info","ts":"2026-08-22T15:01:24.456722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8249,"took":"86.47821ms","hash":2344378079,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":25460736,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T15:01:24.456868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344378079,"revision":8249,"compact-revision":6058} {"level":"info","ts":"2026-08-22T15:01:54.634744Z","caller":"traceutil/trace.go:171","msg":"trace[64858609] transaction","detail":"{read_only:false; response_revision:11078; number_of_response:1; }","duration":"269.075609ms","start":"2026-08-22T15:01:54.365646Z","end":"2026-08-22T15:01:54.634722Z","steps":["trace[64858609] 'process raft request' (duration: 268.921895ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:54.637788Z","caller":"traceutil/trace.go:171","msg":"trace[15743597] transaction","detail":"{read_only:false; response_revision:11079; number_of_response:1; }","duration":"241.457599ms","start":"2026-08-22T15:01:54.396315Z","end":"2026-08-22T15:01:54.637772Z","steps":["trace[15743597] 'process raft request' (duration: 241.251193ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:54.637834Z","caller":"traceutil/trace.go:171","msg":"trace[838571630] transaction","detail":"{read_only:false; response_revision:11080; number_of_response:1; }","duration":"138.063807ms","start":"2026-08-22T15:01:54.499741Z","end":"2026-08-22T15:01:54.637805Z","steps":["trace[838571630] 'process raft request' (duration: 137.957814ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:54.637854Z","caller":"traceutil/trace.go:171","msg":"trace[400792760] transaction","detail":"{read_only:false; response_revision:11081; number_of_response:1; }","duration":"122.191845ms","start":"2026-08-22T15:01:54.515637Z","end":"2026-08-22T15:01:54.637829Z","steps":["trace[400792760] 'process raft request' (duration: 122.114153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.130237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.032011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:55.130308Z","caller":"traceutil/trace.go:171","msg":"trace[794568822] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11084; }","duration":"427.123534ms","start":"2026-08-22T15:01:54.703167Z","end":"2026-08-22T15:01:55.130291Z","steps":["trace[794568822] 'range keys from in-memory index tree' (duration: 426.887037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.130341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.703161Z","time spent":"427.171575ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-08-22T15:01:55.130502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.86718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:55.13053Z","caller":"traceutil/trace.go:171","msg":"trace[1816547339] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11084; }","duration":"429.913691ms","start":"2026-08-22T15:01:54.700608Z","end":"2026-08-22T15:01:55.130522Z","steps":["trace[1816547339] 'range keys from in-memory index tree' (duration: 429.839799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.130552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.700601Z","time spent":"429.944662ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"warn","ts":"2026-08-22T15:01:55.130652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.067256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:55.130679Z","caller":"traceutil/trace.go:171","msg":"trace[73287404] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:0; response_revision:11084; }","duration":"430.105757ms","start":"2026-08-22T15:01:54.700568Z","end":"2026-08-22T15:01:55.130674Z","steps":["trace[73287404] 'range keys from in-memory index tree' (duration: 429.977523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.130697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.700563Z","time spent":"430.129367ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"warn","ts":"2026-08-22T15:01:55.130852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.615237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:55.13088Z","caller":"traceutil/trace.go:171","msg":"trace[934702417] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11084; }","duration":"427.651308ms","start":"2026-08-22T15:01:54.703222Z","end":"2026-08-22T15:01:55.130874Z","steps":["trace[934702417] 'range keys from in-memory index tree' (duration: 427.593286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.130897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.703216Z","time spent":"427.676009ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-08-22T15:01:55.131231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.949177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:55.131258Z","caller":"traceutil/trace.go:171","msg":"trace[725840270] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:0; response_revision:11084; }","duration":"427.985187ms","start":"2026-08-22T15:01:54.703267Z","end":"2026-08-22T15:01:55.131252Z","steps":["trace[725840270] 'range keys from in-memory index tree' (duration: 427.875565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.131275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.703263Z","time spent":"428.006818ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"warn","ts":"2026-08-22T15:01:55.131355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.044459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:55.131382Z","caller":"traceutil/trace.go:171","msg":"trace[363909903] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11084; }","duration":"428.07928ms","start":"2026-08-22T15:01:54.703296Z","end":"2026-08-22T15:01:55.131375Z","steps":["trace[363909903] 'range keys from in-memory index tree' (duration: 428.013348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.131408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.703292Z","time spent":"428.10352ms","remote":"127.0.0.1:55010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-08-22T15:01:55.131248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.839437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-22T15:01:55.131546Z","caller":"traceutil/trace.go:171","msg":"trace[292136931] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11084; }","duration":"431.196847ms","start":"2026-08-22T15:01:54.700317Z","end":"2026-08-22T15:01:55.131514Z","steps":["trace[292136931] 'range keys from in-memory index tree' (duration: 429.739296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:55.131661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:54.700299Z","time spent":"431.343022ms","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"info","ts":"2026-08-22T15:01:56.057839Z","caller":"traceutil/trace.go:171","msg":"trace[1959972061] linearizableReadLoop","detail":"{readStateIndex:11466; appliedIndex:11465; }","duration":"475.228134ms","start":"2026-08-22T15:01:55.582579Z","end":"2026-08-22T15:01:56.057807Z","steps":["trace[1959972061] 'read index received' (duration: 474.988938ms)","trace[1959972061] 'applied index is now lower than readState.Index' (duration: 237.576µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:01:56.057911Z","caller":"traceutil/trace.go:171","msg":"trace[796623888] transaction","detail":"{read_only:false; response_revision:11085; number_of_response:1; }","duration":"476.832109ms","start":"2026-08-22T15:01:55.581054Z","end":"2026-08-22T15:01:56.057886Z","steps":["trace[796623888] 'process raft request' (duration: 476.512901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:56.058109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:55.581036Z","time spent":"476.935743ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":789,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:01:56.058179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.541074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T15:01:56.058303Z","caller":"traceutil/trace.go:171","msg":"trace[2060168542] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11085; }","duration":"475.75033ms","start":"2026-08-22T15:01:55.582522Z","end":"2026-08-22T15:01:56.058272Z","steps":["trace[2060168542] 'agreement among raft nodes before linearized reading' (duration: 475.40626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:56.058365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:55.582501Z","time spent":"475.851532ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T15:01:56.060761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.501449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T15:01:56.060853Z","caller":"traceutil/trace.go:171","msg":"trace[639861057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11086; }","duration":"373.642293ms","start":"2026-08-22T15:01:55.687187Z","end":"2026-08-22T15:01:56.060829Z","steps":["trace[639861057] 'agreement among raft nodes before linearized reading' (duration: 373.357316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:56.060891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.578866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T15:01:56.060905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:01:55.687168Z","time spent":"373.725285ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T15:01:56.060941Z","caller":"traceutil/trace.go:171","msg":"trace[378492611] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:11086; }","duration":"268.659138ms","start":"2026-08-22T15:01:55.792268Z","end":"2026-08-22T15:01:56.060927Z","steps":["trace[378492611] 'agreement among raft nodes before linearized reading' (duration: 268.576686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:56.06093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.466931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:01:56.061061Z","caller":"traceutil/trace.go:171","msg":"trace[1032380396] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:11086; }","duration":"132.646827ms","start":"2026-08-22T15:01:55.928383Z","end":"2026-08-22T15:01:56.06103Z","steps":["trace[1032380396] 'agreement among raft nodes before linearized reading' (duration: 132.304827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:56.061205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.396019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T15:01:56.061257Z","caller":"traceutil/trace.go:171","msg":"trace[184678008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11086; }","duration":"287.447351ms","start":"2026-08-22T15:01:55.773798Z","end":"2026-08-22T15:01:56.061245Z","steps":["trace[184678008] 'agreement among raft nodes before linearized reading' (duration: 287.338688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:01:56.061219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.514903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:01:56.061461Z","caller":"traceutil/trace.go:171","msg":"trace[221692395] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:11086; }","duration":"170.778139ms","start":"2026-08-22T15:01:55.89067Z","end":"2026-08-22T15:01:56.061448Z","steps":["trace[221692395] 'agreement among raft nodes before linearized reading' (duration: 170.404189ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:06:24.378107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10738} {"level":"info","ts":"2026-08-22T15:06:24.454902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10738,"took":"74.723703ms","hash":3638719175,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":26968064,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-22T15:06:24.454963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3638719175,"revision":10738,"compact-revision":8249} {"level":"info","ts":"2026-08-22T15:08:28.782505Z","caller":"traceutil/trace.go:171","msg":"trace[1537997468] transaction","detail":"{read_only:false; response_revision:13771; number_of_response:1; }","duration":"123.181794ms","start":"2026-08-22T15:08:28.659297Z","end":"2026-08-22T15:08:28.782479Z","steps":["trace[1537997468] 'process raft request' (duration: 123.021169ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:28.783024Z","caller":"traceutil/trace.go:171","msg":"trace[2033701931] transaction","detail":"{read_only:false; response_revision:13773; number_of_response:1; }","duration":"123.182656ms","start":"2026-08-22T15:08:28.659828Z","end":"2026-08-22T15:08:28.783011Z","steps":["trace[2033701931] 'process raft request' (duration: 123.142014ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:28.783105Z","caller":"traceutil/trace.go:171","msg":"trace[381285315] transaction","detail":"{read_only:false; response_revision:13772; number_of_response:1; }","duration":"123.318239ms","start":"2026-08-22T15:08:28.659779Z","end":"2026-08-22T15:08:28.783097Z","steps":["trace[381285315] 'process raft request' (duration: 123.138374ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:44.462492Z","caller":"traceutil/trace.go:171","msg":"trace[424067658] transaction","detail":"{read_only:false; response_revision:13864; number_of_response:1; }","duration":"250.429354ms","start":"2026-08-22T15:08:44.212045Z","end":"2026-08-22T15:08:44.462474Z","steps":["trace[424067658] 'process raft request' (duration: 250.313551ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:44.473842Z","caller":"traceutil/trace.go:171","msg":"trace[2045784680] linearizableReadLoop","detail":"{readStateIndex:14344; appliedIndex:14343; }","duration":"252.164852ms","start":"2026-08-22T15:08:44.221656Z","end":"2026-08-22T15:08:44.473821Z","steps":["trace[2045784680] 'read index received' (duration: 241.35513ms)","trace[2045784680] 'applied index is now lower than readState.Index' (duration: 10.808652ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:08:44.473958Z","caller":"traceutil/trace.go:171","msg":"trace[1530379833] transaction","detail":"{read_only:false; response_revision:13865; number_of_response:1; }","duration":"260.971378ms","start":"2026-08-22T15:08:44.212965Z","end":"2026-08-22T15:08:44.473936Z","steps":["trace[1530379833] 'process raft request' (duration: 260.747632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:08:44.474081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.408219ms","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-08-22T15:08:44.47415Z","caller":"traceutil/trace.go:171","msg":"trace[1808640968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13865; }","duration":"252.487541ms","start":"2026-08-22T15:08:44.221642Z","end":"2026-08-22T15:08:44.47413Z","steps":["trace[1808640968] 'agreement among raft nodes before linearized reading' (duration: 252.262395ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:44.617133Z","caller":"traceutil/trace.go:171","msg":"trace[1408534694] transaction","detail":"{read_only:false; response_revision:13866; number_of_response:1; }","duration":"139.231053ms","start":"2026-08-22T15:08:44.477872Z","end":"2026-08-22T15:08:44.617103Z","steps":["trace[1408534694] 'process raft request' (duration: 138.818712ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:09:06.231969Z","caller":"traceutil/trace.go:171","msg":"trace[2126684825] transaction","detail":"{read_only:false; response_revision:14173; number_of_response:1; }","duration":"105.133702ms","start":"2026-08-22T15:09:06.126804Z","end":"2026-08-22T15:09:06.231938Z","steps":["trace[2126684825] 'process raft request' (duration: 104.887375ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:09:06.525667Z","caller":"traceutil/trace.go:171","msg":"trace[572175734] linearizableReadLoop","detail":"{readStateIndex:14665; appliedIndex:14664; }","duration":"292.195879ms","start":"2026-08-22T15:09:06.233432Z","end":"2026-08-22T15:09:06.525628Z","steps":["trace[572175734] 'read index received' (duration: 266.861582ms)","trace[572175734] 'applied index is now lower than readState.Index' (duration: 25.332987ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:09:06.525809Z","caller":"traceutil/trace.go:171","msg":"trace[437100413] transaction","detail":"{read_only:false; response_revision:14174; number_of_response:1; }","duration":"322.311197ms","start":"2026-08-22T15:09:06.203483Z","end":"2026-08-22T15:09:06.525795Z","steps":["trace[437100413] 'process raft request' (duration: 296.949571ms)","trace[437100413] 'compare' (duration: 24.863562ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:09:06.525925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:09:06.203448Z","time spent":"322.39103ms","remote":"127.0.0.1:55046","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-08-22T15:09:06.525973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.530168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-22T15:09:06.526005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.477859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T15:09:06.526061Z","caller":"traceutil/trace.go:171","msg":"trace[1519361859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14174; }","duration":"292.684212ms","start":"2026-08-22T15:09:06.233357Z","end":"2026-08-22T15:09:06.526041Z","steps":["trace[1519361859] 'agreement among raft nodes before linearized reading' (duration: 292.478827ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:09:06.526124Z","caller":"traceutil/trace.go:171","msg":"trace[870063803] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:14174; }","duration":"168.657964ms","start":"2026-08-22T15:09:06.35744Z","end":"2026-08-22T15:09:06.526098Z","steps":["trace[870063803] 'agreement among raft nodes before linearized reading' (duration: 168.414917ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:10:37.372058Z","caller":"traceutil/trace.go:171","msg":"trace[84223867] transaction","detail":"{read_only:false; response_revision:15010; number_of_response:1; }","duration":"118.824364ms","start":"2026-08-22T15:10:37.25318Z","end":"2026-08-22T15:10:37.372005Z","steps":["trace[84223867] 'process raft request' (duration: 118.429122ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:10:37.574995Z","caller":"traceutil/trace.go:171","msg":"trace[1513478718] linearizableReadLoop","detail":"{readStateIndex:15526; appliedIndex:15525; }","duration":"168.247012ms","start":"2026-08-22T15:10:37.406708Z","end":"2026-08-22T15:10:37.574955Z","steps":["trace[1513478718] 'read index received' (duration: 110.08334ms)","trace[1513478718] 'applied index is now lower than readState.Index' (duration: 58.157772ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:10:37.575245Z","caller":"traceutil/trace.go:171","msg":"trace[1231380747] transaction","detail":"{read_only:false; response_revision:15012; number_of_response:1; }","duration":"113.224347ms","start":"2026-08-22T15:10:37.461994Z","end":"2026-08-22T15:10:37.575218Z","steps":["trace[1231380747] 'process raft request' (duration: 112.865187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:10:37.575462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.726065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-22T15:10:37.575522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.356252ms","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-08-22T15:10:37.575523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.057526ms","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-08-22T15:10:37.575561Z","caller":"traceutil/trace.go:171","msg":"trace[1569318424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15012; }","duration":"168.882839ms","start":"2026-08-22T15:10:37.406657Z","end":"2026-08-22T15:10:37.57554Z","steps":["trace[1569318424] 'agreement among raft nodes before linearized reading' (duration: 168.56452ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:10:37.575575Z","caller":"traceutil/trace.go:171","msg":"trace[1757281629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15012; }","duration":"164.450755ms","start":"2026-08-22T15:10:37.41111Z","end":"2026-08-22T15:10:37.575561Z","steps":["trace[1757281629] 'agreement among raft nodes before linearized reading' (duration: 164.232249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:10:37.575547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.861031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18677"} {"level":"info","ts":"2026-08-22T15:10:37.575599Z","caller":"traceutil/trace.go:171","msg":"trace[591386186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15012; }","duration":"163.17851ms","start":"2026-08-22T15:10:37.412405Z","end":"2026-08-22T15:10:37.575583Z","steps":["trace[591386186] 'agreement among raft nodes before linearized reading' (duration: 162.965744ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:10:37.57568Z","caller":"traceutil/trace.go:171","msg":"trace[329856316] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:15012; }","duration":"149.001885ms","start":"2026-08-22T15:10:37.426626Z","end":"2026-08-22T15:10:37.575628Z","steps":["trace[329856316] 'agreement among raft nodes before linearized reading' (duration: 148.723408ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:11:24.385029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12849} {"level":"info","ts":"2026-08-22T15:11:24.462392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12849,"took":"74.60763ms","hash":539248119,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":30334976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T15:11:24.462468Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":539248119,"revision":12849,"compact-revision":10738} {"level":"info","ts":"2026-08-22T15:16:24.39212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15317} {"level":"info","ts":"2026-08-22T15:16:24.520894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15317,"took":"123.869489ms","hash":1378648422,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":31887360,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:16:24.52098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1378648422,"revision":15317,"compact-revision":12849} {"level":"info","ts":"2026-08-22T15:16:26.535835Z","caller":"traceutil/trace.go:171","msg":"trace[2127226690] transaction","detail":"{read_only:false; response_revision:17600; number_of_response:1; }","duration":"240.344932ms","start":"2026-08-22T15:16:26.29545Z","end":"2026-08-22T15:16:26.535795Z","steps":["trace[2127226690] 'process raft request' (duration: 209.306438ms)","trace[2127226690] 'compare' (duration: 30.630563ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:16:26.536144Z","caller":"traceutil/trace.go:171","msg":"trace[1385223442] linearizableReadLoop","detail":"{readStateIndex:18200; appliedIndex:18198; }","duration":"188.54669ms","start":"2026-08-22T15:16:26.347557Z","end":"2026-08-22T15:16:26.536104Z","steps":["trace[1385223442] 'read index received' (duration: 157.203527ms)","trace[1385223442] 'applied index is now lower than readState.Index' (duration: 31.341722ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:16:26.536261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.687054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:16:26.536308Z","caller":"traceutil/trace.go:171","msg":"trace[1181956183] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:17601; }","duration":"188.819487ms","start":"2026-08-22T15:16:26.347475Z","end":"2026-08-22T15:16:26.536295Z","steps":["trace[1181956183] 'agreement among raft nodes before linearized reading' (duration: 188.707084ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:26.536259Z","caller":"traceutil/trace.go:171","msg":"trace[2023794088] transaction","detail":"{read_only:false; response_revision:17601; number_of_response:1; }","duration":"240.701273ms","start":"2026-08-22T15:16:26.295477Z","end":"2026-08-22T15:16:26.536178Z","steps":["trace[2023794088] 'process raft request' (duration: 240.23491ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:27.10001Z","caller":"traceutil/trace.go:171","msg":"trace[1601280922] linearizableReadLoop","detail":"{readStateIndex:18201; appliedIndex:18200; }","duration":"245.26933ms","start":"2026-08-22T15:16:26.854707Z","end":"2026-08-22T15:16:27.099977Z","steps":["trace[1601280922] 'read index received' (duration: 244.963091ms)","trace[1601280922] 'applied index is now lower than readState.Index' (duration: 305.009µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:16:27.100155Z","caller":"traceutil/trace.go:171","msg":"trace[1541410967] transaction","detail":"{read_only:false; response_revision:17602; number_of_response:1; }","duration":"261.900603ms","start":"2026-08-22T15:16:26.838213Z","end":"2026-08-22T15:16:27.100114Z","steps":["trace[1541410967] 'process raft request' (duration: 261.552912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:27.10035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.615199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-htzvj\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-08-22T15:16:27.100395Z","caller":"traceutil/trace.go:171","msg":"trace[1344199071] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-htzvj; range_end:; response_count:1; response_revision:17602; }","duration":"245.739943ms","start":"2026-08-22T15:16:26.85464Z","end":"2026-08-22T15:16:27.100379Z","steps":["trace[1344199071] 'agreement among raft nodes before linearized reading' (duration: 245.521767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:27.100393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.773958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:16:27.100478Z","caller":"traceutil/trace.go:171","msg":"trace[584414979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17602; }","duration":"197.930092ms","start":"2026-08-22T15:16:26.902527Z","end":"2026-08-22T15:16:27.100457Z","steps":["trace[584414979] 'agreement among raft nodes before linearized reading' (duration: 197.685185ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:27.485452Z","caller":"traceutil/trace.go:171","msg":"trace[1680961373] transaction","detail":"{read_only:false; response_revision:17604; number_of_response:1; }","duration":"230.434766ms","start":"2026-08-22T15:16:27.254984Z","end":"2026-08-22T15:16:27.485419Z","steps":["trace[1680961373] 'process raft request' (duration: 229.924702ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:27.809037Z","caller":"traceutil/trace.go:171","msg":"trace[1949366317] transaction","detail":"{read_only:false; response_revision:17606; number_of_response:1; }","duration":"316.563695ms","start":"2026-08-22T15:16:27.49245Z","end":"2026-08-22T15:16:27.809013Z","steps":["trace[1949366317] 'process raft request' (duration: 316.477142ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:16:27.809093Z","caller":"traceutil/trace.go:171","msg":"trace[2024040869] transaction","detail":"{read_only:false; response_revision:17605; number_of_response:1; }","duration":"316.742069ms","start":"2026-08-22T15:16:27.492293Z","end":"2026-08-22T15:16:27.809035Z","steps":["trace[2024040869] 'process raft request' (duration: 316.470902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:27.8092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:27.492439Z","time spent":"316.672687ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:16:27.80934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:27.492265Z","time spent":"316.944555ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:16:27.809422Z","caller":"traceutil/trace.go:171","msg":"trace[1379106214] transaction","detail":"{read_only:false; response_revision:17607; number_of_response:1; }","duration":"316.906174ms","start":"2026-08-22T15:16:27.492502Z","end":"2026-08-22T15:16:27.809408Z","steps":["trace[1379106214] 'process raft request' (duration: 316.465792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:27.809468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:27.492495Z","time spent":"316.948275ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:16:27.809644Z","caller":"traceutil/trace.go:171","msg":"trace[1911659625] transaction","detail":"{read_only:false; response_revision:17608; number_of_response:1; }","duration":"316.790011ms","start":"2026-08-22T15:16:27.492841Z","end":"2026-08-22T15:16:27.809631Z","steps":["trace[1911659625] 'process raft request' (duration: 316.478282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:16:27.809766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:16:27.492829Z","time spent":"316.857143ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:19:58.078983Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:19:58.087152Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T15:19:58.087275Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T15:21:24.399346Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17589} {"level":"info","ts":"2026-08-22T15:21:24.496287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17589,"took":"92.988239ms","hash":1590050827,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":34316288,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T15:21:24.496347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1590050827,"revision":17589,"compact-revision":15317} {"level":"info","ts":"2026-08-22T15:22:04.028876Z","caller":"traceutil/trace.go:171","msg":"trace[1321968054] transaction","detail":"{read_only:false; response_revision:20238; number_of_response:1; }","duration":"180.104276ms","start":"2026-08-22T15:22:03.848712Z","end":"2026-08-22T15:22:04.028817Z","steps":["trace[1321968054] 'process raft request' (duration: 135.827513ms)","trace[1321968054] 'compare' (duration: 44.15535ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:04.325754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.420164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:22:04.325846Z","caller":"traceutil/trace.go:171","msg":"trace[1009190723] transaction","detail":"{read_only:false; response_revision:20239; number_of_response:1; }","duration":"291.718183ms","start":"2026-08-22T15:22:04.03411Z","end":"2026-08-22T15:22:04.325828Z","steps":["trace[1009190723] 'process raft request' (duration: 120.150925ms)","trace[1009190723] 'compare' (duration: 171.205828ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:05.274138Z","caller":"traceutil/trace.go:171","msg":"trace[1662892828] transaction","detail":"{read_only:false; response_revision:20240; number_of_response:1; }","duration":"322.999754ms","start":"2026-08-22T15:22:04.951109Z","end":"2026-08-22T15:22:05.274109Z","steps":["trace[1662892828] 'process raft request' (duration: 322.820659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:05.274301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:04.951073Z","time spent":"323.143178ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:22:05.355931Z","caller":"traceutil/trace.go:171","msg":"trace[1358116434] linearizableReadLoop","detail":"{readStateIndex:20929; appliedIndex:20927; }","duration":"352.733731ms","start":"2026-08-22T15:22:05.003161Z","end":"2026-08-22T15:22:05.355895Z","steps":["trace[1358116434] 'read index received' (duration: 270.855241ms)","trace[1358116434] 'applied index is now lower than readState.Index' (duration: 81.87669ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:05.355965Z","caller":"traceutil/trace.go:171","msg":"trace[1093082622] transaction","detail":"{read_only:false; response_revision:20241; number_of_response:1; }","duration":"390.01889ms","start":"2026-08-22T15:22:04.965902Z","end":"2026-08-22T15:22:05.355921Z","steps":["trace[1093082622] 'process raft request' (duration: 385.622888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:05.356085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.907096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:05.356151Z","caller":"traceutil/trace.go:171","msg":"trace[397558566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20241; }","duration":"353.043061ms","start":"2026-08-22T15:22:05.00309Z","end":"2026-08-22T15:22:05.356133Z","steps":["trace[397558566] 'agreement among raft nodes before linearized reading' (duration: 352.903167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:05.356143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:04.965884Z","time spent":"390.165004ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:05.356207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:05.003062Z","time spent":"353.136064ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T15:22:05.944354Z","caller":"traceutil/trace.go:171","msg":"trace[1748709552] transaction","detail":"{read_only:false; response_revision:20244; number_of_response:1; }","duration":"149.394279ms","start":"2026-08-22T15:22:05.794934Z","end":"2026-08-22T15:22:05.944328Z","steps":["trace[1748709552] 'process raft request' (duration: 149.235995ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:06.026382Z","caller":"traceutil/trace.go:171","msg":"trace[511620320] transaction","detail":"{read_only:false; response_revision:20245; number_of_response:1; }","duration":"231.377543ms","start":"2026-08-22T15:22:05.794966Z","end":"2026-08-22T15:22:06.026343Z","steps":["trace[511620320] 'process raft request' (duration: 231.060894ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:06.026436Z","caller":"traceutil/trace.go:171","msg":"trace[1120690431] linearizableReadLoop","detail":"{readStateIndex:20937; appliedIndex:20931; }","duration":"123.494968ms","start":"2026-08-22T15:22:05.902911Z","end":"2026-08-22T15:22:06.026406Z","steps":["trace[1120690431] 'read index received' (duration: 41.313349ms)","trace[1120690431] 'applied index is now lower than readState.Index' (duration: 82.180149ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:06.02645Z","caller":"traceutil/trace.go:171","msg":"trace[1641111031] transaction","detail":"{read_only:false; response_revision:20246; number_of_response:1; }","duration":"231.355262ms","start":"2026-08-22T15:22:05.79505Z","end":"2026-08-22T15:22:06.026405Z","steps":["trace[1641111031] 'process raft request' (duration: 231.192638ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:06.026491Z","caller":"traceutil/trace.go:171","msg":"trace[424527575] transaction","detail":"{read_only:false; response_revision:20248; number_of_response:1; }","duration":"230.531198ms","start":"2026-08-22T15:22:05.795916Z","end":"2026-08-22T15:22:06.026447Z","steps":["trace[424527575] 'process raft request' (duration: 230.417685ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:06.02653Z","caller":"traceutil/trace.go:171","msg":"trace[570019795] transaction","detail":"{read_only:false; response_revision:20249; number_of_response:1; }","duration":"155.623974ms","start":"2026-08-22T15:22:05.870867Z","end":"2026-08-22T15:22:06.026491Z","steps":["trace[570019795] 'process raft request' (duration: 155.50182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:06.026599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.666984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:06.02658Z","caller":"traceutil/trace.go:171","msg":"trace[682244297] transaction","detail":"{read_only:false; response_revision:20247; number_of_response:1; }","duration":"230.822888ms","start":"2026-08-22T15:22:05.795737Z","end":"2026-08-22T15:22:06.02656Z","steps":["trace[682244297] 'process raft request' (duration: 230.56168ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:06.026642Z","caller":"traceutil/trace.go:171","msg":"trace[272874796] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:20249; }","duration":"123.778556ms","start":"2026-08-22T15:22:05.902848Z","end":"2026-08-22T15:22:06.026627Z","steps":["trace[272874796] 'agreement among raft nodes before linearized reading' (duration: 123.659213ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:26:00.150102Z","caller":"traceutil/trace.go:171","msg":"trace[1135295624] linearizableReadLoop","detail":"{readStateIndex:23441; appliedIndex:23440; }","duration":"140.032162ms","start":"2026-08-22T15:26:00.010034Z","end":"2026-08-22T15:26:00.150067Z","steps":["trace[1135295624] 'read index received' (duration: 139.787305ms)","trace[1135295624] 'applied index is now lower than readState.Index' (duration: 243.917µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:26:00.150406Z","caller":"traceutil/trace.go:171","msg":"trace[1084062233] transaction","detail":"{read_only:false; response_revision:22682; number_of_response:1; }","duration":"208.625318ms","start":"2026-08-22T15:25:59.941772Z","end":"2026-08-22T15:26:00.150397Z","steps":["trace[1084062233] 'process raft request' (duration: 208.133565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:26:00.150682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.132123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:26:00.150717Z","caller":"traceutil/trace.go:171","msg":"trace[1677737879] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:22682; }","duration":"127.242777ms","start":"2026-08-22T15:26:00.023464Z","end":"2026-08-22T15:26:00.150707Z","steps":["trace[1677737879] 'agreement among raft nodes before linearized reading' (duration: 127.158354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:26:00.150712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.640828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:26:00.150835Z","caller":"traceutil/trace.go:171","msg":"trace[1914105872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22682; }","duration":"140.849894ms","start":"2026-08-22T15:26:00.009957Z","end":"2026-08-22T15:26:00.150807Z","steps":["trace[1914105872] 'agreement among raft nodes before linearized reading' (duration: 140.624998ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:26:24.40536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20036} {"level":"info","ts":"2026-08-22T15:26:24.525288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20036,"took":"114.194763ms","hash":2128066616,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":41590784,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-22T15:26:24.525399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2128066616,"revision":20036,"compact-revision":17589} {"level":"info","ts":"2026-08-22T15:29:17.432666Z","caller":"traceutil/trace.go:171","msg":"trace[328617484] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"139.558509ms","start":"2026-08-22T15:29:17.293076Z","end":"2026-08-22T15:29:17.432634Z","steps":["trace[328617484] 'process raft request' (duration: 139.310682ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:18.104414Z","caller":"traceutil/trace.go:171","msg":"trace[1394187895] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"334.908079ms","start":"2026-08-22T15:29:17.769445Z","end":"2026-08-22T15:29:18.104353Z","steps":["trace[1394187895] 'process raft request' (duration: 334.713284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:18.105409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:17.769412Z","time spent":"335.886025ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:18.106995Z","caller":"traceutil/trace.go:171","msg":"trace[1704895406] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"337.518392ms","start":"2026-08-22T15:29:17.769444Z","end":"2026-08-22T15:29:18.106963Z","steps":["trace[1704895406] 'process raft request' (duration: 337.205693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:18.107201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:17.769413Z","time spent":"337.679885ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:18.107377Z","caller":"traceutil/trace.go:171","msg":"trace[1942425675] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"275.813795ms","start":"2026-08-22T15:29:17.831552Z","end":"2026-08-22T15:29:18.107366Z","steps":["trace[1942425675] 'process raft request' (duration: 275.357812ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:18.107357Z","caller":"traceutil/trace.go:171","msg":"trace[1504415176] linearizableReadLoop","detail":"{readStateIndex:25511; appliedIndex:25508; }","duration":"275.651811ms","start":"2026-08-22T15:29:17.831681Z","end":"2026-08-22T15:29:18.107333Z","steps":["trace[1504415176] 'read index received' (duration: 272.522404ms)","trace[1504415176] 'applied index is now lower than readState.Index' (duration: 3.128447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:18.107547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.846086ms","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":"warn","ts":"2026-08-22T15:29:18.107566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.092159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:18.107591Z","caller":"traceutil/trace.go:171","msg":"trace[1170392800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24699; }","duration":"275.958529ms","start":"2026-08-22T15:29:17.831622Z","end":"2026-08-22T15:29:18.10758Z","steps":["trace[1170392800] 'agreement among raft nodes before linearized reading' (duration: 275.782514ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:18.107609Z","caller":"traceutil/trace.go:171","msg":"trace[1266601093] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24699; }","duration":"115.178851ms","start":"2026-08-22T15:29:17.992414Z","end":"2026-08-22T15:29:18.107593Z","steps":["trace[1266601093] 'agreement among raft nodes before linearized reading' (duration: 115.106199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:22.644808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.93823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:29:22.644941Z","caller":"traceutil/trace.go:171","msg":"trace[994338145] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"293.447876ms","start":"2026-08-22T15:29:22.351471Z","end":"2026-08-22T15:29:22.644919Z","steps":["trace[994338145] 'process raft request' (duration: 126.29363ms)","trace[994338145] 'compare' (duration: 166.775945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:22.940519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.963813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:29:22.941129Z","caller":"traceutil/trace.go:171","msg":"trace[378137039] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"242.818288ms","start":"2026-08-22T15:29:22.69828Z","end":"2026-08-22T15:29:22.941098Z","steps":["trace[378137039] 'process raft request' (duration: 90.140635ms)","trace[378137039] 'compare' (duration: 151.816279ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:23.083401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.700657ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T15:29:23.083501Z","caller":"traceutil/trace.go:171","msg":"trace[880775739] linearizableReadLoop","detail":"{readStateIndex:25553; appliedIndex:25552; }","duration":"142.237043ms","start":"2026-08-22T15:29:22.941248Z","end":"2026-08-22T15:29:23.083485Z","steps":["trace[880775739] 'read index received' (duration: 97.263µs)","trace[880775739] 'applied index is now lower than readState.Index' (duration: 142.13783ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:23.083704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:29:23.083812Z","caller":"traceutil/trace.go:171","msg":"trace[529290059] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24739; }","duration":"147.955151ms","start":"2026-08-22T15:29:22.935829Z","end":"2026-08-22T15:29:23.083784Z","steps":["trace[529290059] 'agreement among raft nodes before linearized reading' (duration: 147.717494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:23.121718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:23.121833Z","caller":"traceutil/trace.go:171","msg":"trace[1821438131] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24740; }","duration":"148.431176ms","start":"2026-08-22T15:29:22.973375Z","end":"2026-08-22T15:29:23.121806Z","steps":["trace[1821438131] 'agreement among raft nodes before linearized reading' (duration: 148.21866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:23.122114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.085259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:23.122262Z","caller":"traceutil/trace.go:171","msg":"trace[1932563757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24740; }","duration":"119.285754ms","start":"2026-08-22T15:29:23.002935Z","end":"2026-08-22T15:29:23.122221Z","steps":["trace[1932563757] 'agreement among raft nodes before linearized reading' (duration: 119.093299ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:23.886678Z","caller":"traceutil/trace.go:171","msg":"trace[1261704502] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"160.218602ms","start":"2026-08-22T15:29:23.72643Z","end":"2026-08-22T15:29:23.886649Z","steps":["trace[1261704502] 'process raft request' (duration: 159.972355ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:24.283594Z","caller":"traceutil/trace.go:171","msg":"trace[145907162] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"149.090475ms","start":"2026-08-22T15:29:24.134462Z","end":"2026-08-22T15:29:24.283552Z","steps":["trace[145907162] 'process raft request' (duration: 146.100962ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:24.283653Z","caller":"traceutil/trace.go:171","msg":"trace[954345770] linearizableReadLoop","detail":"{readStateIndex:25560; appliedIndex:25557; }","duration":"144.807625ms","start":"2026-08-22T15:29:24.138815Z","end":"2026-08-22T15:29:24.283623Z","steps":["trace[954345770] 'read index received' (duration: 31.896156ms)","trace[954345770] 'applied index is now lower than readState.Index' (duration: 112.910059ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:24.283818Z","caller":"traceutil/trace.go:171","msg":"trace[1419751763] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"149.256988ms","start":"2026-08-22T15:29:24.134547Z","end":"2026-08-22T15:29:24.283804Z","steps":["trace[1419751763] 'process raft request' (duration: 148.472036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.284199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.375831ms","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-08-22T15:29:24.284269Z","caller":"traceutil/trace.go:171","msg":"trace[433333021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24747; }","duration":"145.493804ms","start":"2026-08-22T15:29:24.138758Z","end":"2026-08-22T15:29:24.284251Z","steps":["trace[433333021] 'agreement among raft nodes before linearized reading' (duration: 145.209116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.284422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.065782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:29:24.284468Z","caller":"traceutil/trace.go:171","msg":"trace[1861785590] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:24747; }","duration":"131.112915ms","start":"2026-08-22T15:29:24.153341Z","end":"2026-08-22T15:29:24.284454Z","steps":["trace[1861785590] 'agreement among raft nodes before linearized reading' (duration: 130.962491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.284205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.19885ms","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-08-22T15:29:24.284564Z","caller":"traceutil/trace.go:171","msg":"trace[9948695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24747; }","duration":"143.601301ms","start":"2026-08-22T15:29:24.14094Z","end":"2026-08-22T15:29:24.284542Z","steps":["trace[9948695] 'agreement among raft nodes before linearized reading' (duration: 143.122788ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:24.28464Z","caller":"traceutil/trace.go:171","msg":"trace[630892585] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"145.461803ms","start":"2026-08-22T15:29:24.139104Z","end":"2026-08-22T15:29:24.284566Z","steps":["trace[630892585] 'process raft request' (duration: 144.453145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.604938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.674483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:29:24.605155Z","caller":"traceutil/trace.go:171","msg":"trace[1757788286] linearizableReadLoop","detail":"{readStateIndex:25564; appliedIndex:25561; }","duration":"275.846766ms","start":"2026-08-22T15:29:24.329268Z","end":"2026-08-22T15:29:24.605114Z","steps":["trace[1757788286] 'read index received' (duration: 163.908306ms)","trace[1757788286] 'applied index is now lower than readState.Index' (duration: 111.9373ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:24.605222Z","caller":"traceutil/trace.go:171","msg":"trace[374153075] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"313.476493ms","start":"2026-08-22T15:29:24.291736Z","end":"2026-08-22T15:29:24.605213Z","steps":["trace[374153075] 'process raft request' (duration: 201.442529ms)","trace[374153075] 'compare' (duration: 111.53579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:24.605264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:24.291709Z","time spent":"313.531134ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:24.605433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.158535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:29:24.605506Z","caller":"traceutil/trace.go:171","msg":"trace[782884583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24750; }","duration":"276.272749ms","start":"2026-08-22T15:29:24.329216Z","end":"2026-08-22T15:29:24.605489Z","steps":["trace[782884583] 'agreement among raft nodes before linearized reading' (duration: 276.081574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.605532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.657998ms","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-08-22T15:29:24.605557Z","caller":"traceutil/trace.go:171","msg":"trace[416226368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24750; }","duration":"263.706739ms","start":"2026-08-22T15:29:24.341843Z","end":"2026-08-22T15:29:24.605549Z","steps":["trace[416226368] 'agreement among raft nodes before linearized reading' (duration: 263.591146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.605665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.296067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T15:29:24.605681Z","caller":"traceutil/trace.go:171","msg":"trace[1030805148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24750; }","duration":"254.336479ms","start":"2026-08-22T15:29:24.351339Z","end":"2026-08-22T15:29:24.605676Z","steps":["trace[1030805148] 'agreement among raft nodes before linearized reading' (duration: 254.283917ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:24.605711Z","caller":"traceutil/trace.go:171","msg":"trace[1946038271] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"313.582886ms","start":"2026-08-22T15:29:24.292105Z","end":"2026-08-22T15:29:24.605688Z","steps":["trace[1946038271] 'process raft request' (duration: 312.931007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:24.605804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:24.292086Z","time spent":"313.648097ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:24.605932Z","caller":"traceutil/trace.go:171","msg":"trace[1363721991] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"290.736321ms","start":"2026-08-22T15:29:24.315186Z","end":"2026-08-22T15:29:24.605923Z","steps":["trace[1363721991] 'process raft request' (duration: 289.895017ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:24.821137Z","caller":"traceutil/trace.go:171","msg":"trace[52001812] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"147.950312ms","start":"2026-08-22T15:29:24.673151Z","end":"2026-08-22T15:29:24.821101Z","steps":["trace[52001812] 'process raft request' (duration: 147.724675ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:26.378382Z","caller":"traceutil/trace.go:171","msg":"trace[1665012601] linearizableReadLoop","detail":"{readStateIndex:25573; appliedIndex:25572; }","duration":"183.711785ms","start":"2026-08-22T15:29:26.194636Z","end":"2026-08-22T15:29:26.378347Z","steps":["trace[1665012601] 'read index received' (duration: 183.562211ms)","trace[1665012601] 'applied index is now lower than readState.Index' (duration: 147.794µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:26.378434Z","caller":"traceutil/trace.go:171","msg":"trace[848410218] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"201.429767ms","start":"2026-08-22T15:29:26.176945Z","end":"2026-08-22T15:29:26.378374Z","steps":["trace[848410218] 'process raft request' (duration: 201.19377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:26.378549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.890321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:26.378603Z","caller":"traceutil/trace.go:171","msg":"trace[2014692128] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24759; }","duration":"184.026936ms","start":"2026-08-22T15:29:26.194559Z","end":"2026-08-22T15:29:26.378586Z","steps":["trace[2014692128] 'agreement among raft nodes before linearized reading' (duration: 183.913562ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:26.389107Z","caller":"traceutil/trace.go:171","msg":"trace[2117866853] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"100.04747ms","start":"2026-08-22T15:29:26.289037Z","end":"2026-08-22T15:29:26.389084Z","steps":["trace[2117866853] 'process raft request' (duration: 99.745831ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:27.050628Z","caller":"traceutil/trace.go:171","msg":"trace[1101303406] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"352.554328ms","start":"2026-08-22T15:29:26.698032Z","end":"2026-08-22T15:29:27.050586Z","steps":["trace[1101303406] 'process raft request' (duration: 352.119896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.050898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:26.698004Z","time spent":"352.774374ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:27.051023Z","caller":"traceutil/trace.go:171","msg":"trace[272105252] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"352.621201ms","start":"2026-08-22T15:29:26.69836Z","end":"2026-08-22T15:29:27.050982Z","steps":["trace[272105252] 'process raft request' (duration: 352.147527ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:27.051091Z","caller":"traceutil/trace.go:171","msg":"trace[631909266] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"352.5886ms","start":"2026-08-22T15:29:26.698474Z","end":"2026-08-22T15:29:27.051063Z","steps":["trace[631909266] 'process raft request' (duration: 352.373664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.051194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:26.698438Z","time spent":"352.702693ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:27.051194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:26.698347Z","time spent":"352.755424ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:27.051221Z","caller":"traceutil/trace.go:171","msg":"trace[534091368] linearizableReadLoop","detail":"{readStateIndex:25580; appliedIndex:25576; }","duration":"224.653865ms","start":"2026-08-22T15:29:26.826531Z","end":"2026-08-22T15:29:27.051184Z","steps":["trace[534091368] 'read index received' (duration: 223.601775ms)","trace[534091368] 'applied index is now lower than readState.Index' (duration: 1.0505ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:27.051328Z","caller":"traceutil/trace.go:171","msg":"trace[428013489] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"352.449256ms","start":"2026-08-22T15:29:26.698866Z","end":"2026-08-22T15:29:27.051315Z","steps":["trace[428013489] 'process raft request' (duration: 352.143787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.051382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:26.698856Z","time spent":"352.494678ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:27.05152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.926912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T15:29:27.051599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.051665ms","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-08-22T15:29:27.051631Z","caller":"traceutil/trace.go:171","msg":"trace[1871175838] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24767; }","duration":"139.086146ms","start":"2026-08-22T15:29:26.912525Z","end":"2026-08-22T15:29:27.051611Z","steps":["trace[1871175838] 'agreement among raft nodes before linearized reading' (duration: 138.908081ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:27.051669Z","caller":"traceutil/trace.go:171","msg":"trace[961811886] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24767; }","duration":"225.174039ms","start":"2026-08-22T15:29:26.826474Z","end":"2026-08-22T15:29:27.051648Z","steps":["trace[961811886] 'agreement among raft nodes before linearized reading' (duration: 224.938592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.225412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.878619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:29:27.225829Z","caller":"traceutil/trace.go:171","msg":"trace[1541580122] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"167.426514ms","start":"2026-08-22T15:29:27.05836Z","end":"2026-08-22T15:29:27.225786Z","steps":["trace[1541580122] 'process raft request' (duration: 50.047631ms)","trace[1541580122] 'compare' (duration: 116.685154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:27.529837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.166781ms","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-08-22T15:29:27.529942Z","caller":"traceutil/trace.go:171","msg":"trace[1029171470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24769; }","duration":"151.313946ms","start":"2026-08-22T15:29:27.378603Z","end":"2026-08-22T15:29:27.529917Z","steps":["trace[1029171470] 'count revisions from in-memory index tree' (duration: 150.944795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.823498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.633218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:29:27.823633Z","caller":"traceutil/trace.go:171","msg":"trace[134835990] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"269.822099ms","start":"2026-08-22T15:29:27.553787Z","end":"2026-08-22T15:29:27.823609Z","steps":["trace[134835990] 'process raft request' (duration: 123.944574ms)","trace[134835990] 'compare' (duration: 145.473894ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:27.986052Z","caller":"traceutil/trace.go:171","msg":"trace[928390278] linearizableReadLoop","detail":"{readStateIndex:25587; appliedIndex:25585; }","duration":"277.905533ms","start":"2026-08-22T15:29:27.708113Z","end":"2026-08-22T15:29:27.986018Z","steps":["trace[928390278] 'read index received' (duration: 232.344267ms)","trace[928390278] 'applied index is now lower than readState.Index' (duration: 45.559846ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:27.986071Z","caller":"traceutil/trace.go:171","msg":"trace[674494876] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"340.40697ms","start":"2026-08-22T15:29:27.64561Z","end":"2026-08-22T15:29:27.986017Z","steps":["trace[674494876] 'process raft request' (duration: 294.936457ms)","trace[674494876] 'compare' (duration: 45.017931ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:27.986186Z","caller":"traceutil/trace.go:171","msg":"trace[2129275740] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"326.74449ms","start":"2026-08-22T15:29:27.659411Z","end":"2026-08-22T15:29:27.986155Z","steps":["trace[2129275740] 'process raft request' (duration: 326.452182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.986262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:27.645575Z","time spent":"340.583445ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:27.986278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.13958ms","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-08-22T15:29:27.986327Z","caller":"traceutil/trace.go:171","msg":"trace[1129452533] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24772; }","duration":"278.266594ms","start":"2026-08-22T15:29:27.708046Z","end":"2026-08-22T15:29:27.986313Z","steps":["trace[1129452533] 'agreement among raft nodes before linearized reading' (duration: 278.14265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:27.98635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:27.659373Z","time spent":"326.877685ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:27.986554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.101981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-22T15:29:27.986592Z","caller":"traceutil/trace.go:171","msg":"trace[1373309623] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24772; }","duration":"160.185243ms","start":"2026-08-22T15:29:27.826397Z","end":"2026-08-22T15:29:27.986582Z","steps":["trace[1373309623] 'agreement among raft nodes before linearized reading' (duration: 160.09611ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:37.537627Z","caller":"traceutil/trace.go:171","msg":"trace[297749128] linearizableReadLoop","detail":"{readStateIndex:25658; appliedIndex:25657; }","duration":"245.694028ms","start":"2026-08-22T15:29:37.291909Z","end":"2026-08-22T15:29:37.537603Z","steps":["trace[297749128] 'read index received' (duration: 245.538884ms)","trace[297749128] 'applied index is now lower than readState.Index' (duration: 154.094µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:37.537921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.982557ms","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-08-22T15:29:37.537959Z","caller":"traceutil/trace.go:171","msg":"trace[1276976096] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24842; }","duration":"246.07692ms","start":"2026-08-22T15:29:37.291868Z","end":"2026-08-22T15:29:37.537945Z","steps":["trace[1276976096] 'agreement among raft nodes before linearized reading' (duration: 245.885814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:37.53796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.356653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:37.538053Z","caller":"traceutil/trace.go:171","msg":"trace[1695970190] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24842; }","duration":"166.525628ms","start":"2026-08-22T15:29:37.371508Z","end":"2026-08-22T15:29:37.538034Z","steps":["trace[1695970190] 'agreement among raft nodes before linearized reading' (duration: 166.299742ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:37.53776Z","caller":"traceutil/trace.go:171","msg":"trace[1425491927] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"272.620627ms","start":"2026-08-22T15:29:37.265102Z","end":"2026-08-22T15:29:37.537723Z","steps":["trace[1425491927] 'process raft request' (duration: 272.334819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.31182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.855549ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-22T15:29:38.312723Z","caller":"traceutil/trace.go:171","msg":"trace[77168000] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"281.565186ms","start":"2026-08-22T15:29:38.031131Z","end":"2026-08-22T15:29:38.312696Z","steps":["trace[77168000] 'process raft request' (duration: 281.504134ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:38.312806Z","caller":"traceutil/trace.go:171","msg":"trace[896401969] linearizableReadLoop","detail":"{readStateIndex:25660; appliedIndex:25658; }","duration":"649.127551ms","start":"2026-08-22T15:29:37.663613Z","end":"2026-08-22T15:29:38.312741Z","steps":["trace[896401969] 'read index received' (duration: 15.374696ms)","trace[896401969] 'applied index is now lower than readState.Index' (duration: 633.750795ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:38.312809Z","caller":"traceutil/trace.go:171","msg":"trace[569231688] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"770.195526ms","start":"2026-08-22T15:29:37.54253Z","end":"2026-08-22T15:29:38.312725Z","steps":["trace[569231688] 'process raft request' (duration: 769.936819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.312972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.326317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:29:38.31301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:37.542515Z","time spent":"770.392802ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:38.312983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:37.539683Z","time spent":"773.293052ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T15:29:38.313077Z","caller":"traceutil/trace.go:171","msg":"trace[1156694113] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24844; }","duration":"649.489702ms","start":"2026-08-22T15:29:37.663562Z","end":"2026-08-22T15:29:38.313052Z","steps":["trace[1156694113] 'agreement among raft nodes before linearized reading' (duration: 649.296836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.313366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:37.663533Z","time spent":"649.794339ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:29:38.730449Z","caller":"traceutil/trace.go:171","msg":"trace[130864161] linearizableReadLoop","detail":"{readStateIndex:25662; appliedIndex:25661; }","duration":"417.566215ms","start":"2026-08-22T15:29:38.312836Z","end":"2026-08-22T15:29:38.730402Z","steps":["trace[130864161] 'read index received' (duration: 417.429101ms)","trace[130864161] 'applied index is now lower than readState.Index' (duration: 135.954µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:38.730565Z","caller":"traceutil/trace.go:171","msg":"trace[605959722] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"693.050193ms","start":"2026-08-22T15:29:38.037472Z","end":"2026-08-22T15:29:38.730523Z","steps":["trace[605959722] 'process raft request' (duration: 692.758004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.730734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.037438Z","time spent":"693.202857ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:38.730739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.236882ms","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-08-22T15:29:38.730788Z","caller":"traceutil/trace.go:171","msg":"trace[1320679534] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24845; }","duration":"727.312794ms","start":"2026-08-22T15:29:38.003461Z","end":"2026-08-22T15:29:38.730774Z","steps":["trace[1320679534] 'agreement among raft nodes before linearized reading' (duration: 727.119529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.730828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.003386Z","time spent":"727.431918ms","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":90,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:29:38.767861Z","caller":"traceutil/trace.go:171","msg":"trace[2134470433] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"264.599383ms","start":"2026-08-22T15:29:38.503234Z","end":"2026-08-22T15:29:38.767834Z","steps":["trace[2134470433] 'process raft request' (duration: 264.418528ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:38.767854Z","caller":"traceutil/trace.go:171","msg":"trace[1098824133] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"256.127048ms","start":"2026-08-22T15:29:38.511687Z","end":"2026-08-22T15:29:38.767814Z","steps":["trace[1098824133] 'process raft request' (duration: 256.028195ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:38.768123Z","caller":"traceutil/trace.go:171","msg":"trace[1087735373] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"254.823943ms","start":"2026-08-22T15:29:38.513284Z","end":"2026-08-22T15:29:38.768108Z","steps":["trace[1087735373] 'process raft request' (duration: 254.483933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.768244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.368014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:38.76829Z","caller":"traceutil/trace.go:171","msg":"trace[794740565] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24849; }","duration":"320.484897ms","start":"2026-08-22T15:29:38.447792Z","end":"2026-08-22T15:29:38.768277Z","steps":["trace[794740565] 'agreement among raft nodes before linearized reading' (duration: 320.327753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.768321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.447765Z","time spent":"320.548418ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-08-22T15:29:38.767875Z","caller":"traceutil/trace.go:171","msg":"trace[106305871] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"452.770295ms","start":"2026-08-22T15:29:38.315039Z","end":"2026-08-22T15:29:38.767809Z","steps":["trace[106305871] 'process raft request' (duration: 452.418225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:38.768925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.315003Z","time spent":"453.556186ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:39.284086Z","caller":"traceutil/trace.go:171","msg":"trace[387429094] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"504.175043ms","start":"2026-08-22T15:29:38.779889Z","end":"2026-08-22T15:29:39.284064Z","steps":["trace[387429094] 'process raft request' (duration: 503.499514ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:39.284145Z","caller":"traceutil/trace.go:171","msg":"trace[2084979416] linearizableReadLoop","detail":"{readStateIndex:25667; appliedIndex:25667; }","duration":"468.365727ms","start":"2026-08-22T15:29:38.81575Z","end":"2026-08-22T15:29:39.284115Z","steps":["trace[2084979416] 'read index received' (duration: 468.354217ms)","trace[2084979416] 'applied index is now lower than readState.Index' (duration: 9.9µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:39.284243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.779862Z","time spent":"504.319137ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T15:29:39.284335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.580752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:39.28438Z","caller":"traceutil/trace.go:171","msg":"trace[1677684620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24850; }","duration":"468.679846ms","start":"2026-08-22T15:29:38.815689Z","end":"2026-08-22T15:29:39.284369Z","steps":["trace[1677684620] 'agreement among raft nodes before linearized reading' (duration: 468.562323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.284413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.815665Z","time spent":"468.741237ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T15:29:39.285557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.406628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:29:39.28561Z","caller":"traceutil/trace.go:171","msg":"trace[699982644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24852; }","duration":"459.473981ms","start":"2026-08-22T15:29:38.826123Z","end":"2026-08-22T15:29:39.285597Z","steps":["trace[699982644] 'agreement among raft nodes before linearized reading' (duration: 459.307106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.285644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.826115Z","time spent":"459.520502ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T15:29:39.285787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.318977ms","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":"warn","ts":"2026-08-22T15:29:39.285808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.912598ms","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-08-22T15:29:39.28609Z","caller":"traceutil/trace.go:171","msg":"trace[1817074264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24852; }","duration":"163.262647ms","start":"2026-08-22T15:29:39.122811Z","end":"2026-08-22T15:29:39.286074Z","steps":["trace[1817074264] 'agreement among raft nodes before linearized reading' (duration: 162.801014ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:39.285859Z","caller":"traceutil/trace.go:171","msg":"trace[411384671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24852; }","duration":"164.43842ms","start":"2026-08-22T15:29:39.1214Z","end":"2026-08-22T15:29:39.285838Z","steps":["trace[411384671] 'agreement among raft nodes before linearized reading' (duration: 164.221914ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:39.28591Z","caller":"traceutil/trace.go:171","msg":"trace[469266553] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"378.89743ms","start":"2026-08-22T15:29:38.907005Z","end":"2026-08-22T15:29:39.285903Z","steps":["trace[469266553] 'process raft request' (duration: 378.273693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.286338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:38.906978Z","time spent":"379.277701ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:39.285965Z","caller":"traceutil/trace.go:171","msg":"trace[2083308355] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"179.452217ms","start":"2026-08-22T15:29:39.106494Z","end":"2026-08-22T15:29:39.285946Z","steps":["trace[2083308355] 'process raft request' (duration: 178.888132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.286011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.130999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:39.286572Z","caller":"traceutil/trace.go:171","msg":"trace[1811405920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24852; }","duration":"283.741526ms","start":"2026-08-22T15:29:39.002822Z","end":"2026-08-22T15:29:39.286564Z","steps":["trace[1811405920] 'agreement among raft nodes before linearized reading' (duration: 283.15183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.286025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.193008ms","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-08-22T15:29:39.286712Z","caller":"traceutil/trace.go:171","msg":"trace[1802769743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24852; }","duration":"166.884057ms","start":"2026-08-22T15:29:39.119822Z","end":"2026-08-22T15:29:39.286706Z","steps":["trace[1802769743] 'agreement among raft nodes before linearized reading' (duration: 166.134747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.286234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.244375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T15:29:39.287043Z","caller":"traceutil/trace.go:171","msg":"trace[848096410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24852; }","duration":"165.174221ms","start":"2026-08-22T15:29:39.121846Z","end":"2026-08-22T15:29:39.287021Z","steps":["trace[848096410] 'agreement among raft nodes before linearized reading' (duration: 163.824674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.825123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219123935565529988,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T15:29:39.8672Z","caller":"traceutil/trace.go:171","msg":"trace[2041157740] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"573.16369ms","start":"2026-08-22T15:29:39.294012Z","end":"2026-08-22T15:29:39.867176Z","steps":["trace[2041157740] 'process raft request' (duration: 573.066536ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:39.867239Z","caller":"traceutil/trace.go:171","msg":"trace[268201672] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"574.93837ms","start":"2026-08-22T15:29:39.292248Z","end":"2026-08-22T15:29:39.867187Z","steps":["trace[268201672] 'process raft request' (duration: 574.654461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.867378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.293987Z","time spent":"573.270233ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:39.867432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.292234Z","time spent":"575.080154ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:39.867495Z","caller":"traceutil/trace.go:171","msg":"trace[1570314382] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"572.219104ms","start":"2026-08-22T15:29:39.295256Z","end":"2026-08-22T15:29:39.867475Z","steps":["trace[1570314382] 'process raft request' (duration: 571.873025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.8676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.295244Z","time spent":"572.311236ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:39.867783Z","caller":"traceutil/trace.go:171","msg":"trace[1353121856] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"571.335699ms","start":"2026-08-22T15:29:39.296437Z","end":"2026-08-22T15:29:39.867773Z","steps":["trace[1353121856] 'process raft request' (duration: 570.964549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.867832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.296407Z","time spent":"571.401041ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:39.867853Z","caller":"traceutil/trace.go:171","msg":"trace[1790012368] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"562.953457ms","start":"2026-08-22T15:29:39.30487Z","end":"2026-08-22T15:29:39.867824Z","steps":["trace[1790012368] 'process raft request' (duration: 562.813703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:39.868081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.304857Z","time spent":"563.08793ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:29:40.015442Z","caller":"traceutil/trace.go:171","msg":"trace[264649270] linearizableReadLoop","detail":"{readStateIndex:25676; appliedIndex:25670; }","duration":"690.413118ms","start":"2026-08-22T15:29:39.32501Z","end":"2026-08-22T15:29:40.015423Z","steps":["trace[264649270] 'read index received' (duration: 541.956902ms)","trace[264649270] 'applied index is now lower than readState.Index' (duration: 148.455136ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:40.01552Z","caller":"traceutil/trace.go:171","msg":"trace[1743521690] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"471.231416ms","start":"2026-08-22T15:29:39.544232Z","end":"2026-08-22T15:29:40.015463Z","steps":["trace[1743521690] 'process raft request' (duration: 443.203567ms)","trace[1743521690] 'compare' (duration: 27.740322ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:29:40.015724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.544199Z","time spent":"471.412372ms","remote":"127.0.0.1:55046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:29:40.015791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.900674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-08-22T15:29:40.015915Z","caller":"traceutil/trace.go:171","msg":"trace[33784480] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24859; }","duration":"722.047658ms","start":"2026-08-22T15:29:39.293831Z","end":"2026-08-22T15:29:40.015879Z","steps":["trace[33784480] 'agreement among raft nodes before linearized reading' (duration: 721.686037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:40.015976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.29382Z","time spent":"722.139831ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-22T15:29:40.067141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.896774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:40.067254Z","caller":"traceutil/trace.go:171","msg":"trace[940962434] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24860; }","duration":"740.10489ms","start":"2026-08-22T15:29:39.327125Z","end":"2026-08-22T15:29:40.06723Z","steps":["trace[940962434] 'agreement among raft nodes before linearized reading' (duration: 739.867453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:40.067313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.327104Z","time spent":"740.193432ms","remote":"127.0.0.1:57786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:29:40.067455Z","caller":"traceutil/trace.go:171","msg":"trace[257819488] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"119.020018ms","start":"2026-08-22T15:29:39.948391Z","end":"2026-08-22T15:29:40.067411Z","steps":["trace[257819488] 'process raft request' (duration: 118.402191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:40.067502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.117482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T15:29:40.06756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.364107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:40.067604Z","caller":"traceutil/trace.go:171","msg":"trace[245033213] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24860; }","duration":"132.281316ms","start":"2026-08-22T15:29:39.935295Z","end":"2026-08-22T15:29:40.067576Z","steps":["trace[245033213] 'agreement among raft nodes before linearized reading' (duration: 132.095011ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:40.067643Z","caller":"traceutil/trace.go:171","msg":"trace[1097726299] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24860; }","duration":"636.52497ms","start":"2026-08-22T15:29:39.4311Z","end":"2026-08-22T15:29:40.067625Z","steps":["trace[1097726299] 'agreement among raft nodes before linearized reading' (duration: 636.397257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:40.067685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:39.431064Z","time spent":"636.603813ms","remote":"127.0.0.1:54988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:29:41.408556Z","caller":"traceutil/trace.go:171","msg":"trace[1898656431] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"112.095195ms","start":"2026-08-22T15:29:41.296415Z","end":"2026-08-22T15:29:41.40851Z","steps":["trace[1898656431] 'process raft request' (duration: 111.466797ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:43.549333Z","caller":"traceutil/trace.go:171","msg":"trace[913640619] linearizableReadLoop","detail":"{readStateIndex:25759; appliedIndex:25758; }","duration":"130.793085ms","start":"2026-08-22T15:29:43.418509Z","end":"2026-08-22T15:29:43.549302Z","steps":["trace[913640619] 'read index received' (duration: 130.533108ms)","trace[913640619] 'applied index is now lower than readState.Index' (duration: 258.337µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:43.549755Z","caller":"traceutil/trace.go:171","msg":"trace[2147004395] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"136.116453ms","start":"2026-08-22T15:29:43.413619Z","end":"2026-08-22T15:29:43.549736Z","steps":["trace[2147004395] 'process raft request' (duration: 135.404964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:43.550185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.644009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2767388a-a459-4920-99e9-db610076dae0\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-08-22T15:29:43.550238Z","caller":"traceutil/trace.go:171","msg":"trace[539823115] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2767388a-a459-4920-99e9-db610076dae0; range_end:; response_count:1; response_revision:24941; }","duration":"131.739952ms","start":"2026-08-22T15:29:43.418482Z","end":"2026-08-22T15:29:43.550222Z","steps":["trace[539823115] 'agreement among raft nodes before linearized reading' (duration: 131.525526ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:46.991822Z","caller":"traceutil/trace.go:171","msg":"trace[1914584637] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"196.499561ms","start":"2026-08-22T15:29:46.795291Z","end":"2026-08-22T15:29:46.99179Z","steps":["trace[1914584637] 'process raft request' (duration: 196.048379ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:46.99215Z","caller":"traceutil/trace.go:171","msg":"trace[1501073630] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"196.359978ms","start":"2026-08-22T15:29:46.795776Z","end":"2026-08-22T15:29:46.992136Z","steps":["trace[1501073630] 'process raft request' (duration: 195.951516ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:46.992263Z","caller":"traceutil/trace.go:171","msg":"trace[889915944] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"195.208235ms","start":"2026-08-22T15:29:46.797024Z","end":"2026-08-22T15:29:46.992232Z","steps":["trace[889915944] 'process raft request' (duration: 194.990219ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:55.031598Z","caller":"traceutil/trace.go:171","msg":"trace[610710284] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"149.880676ms","start":"2026-08-22T15:29:54.881672Z","end":"2026-08-22T15:29:55.031552Z","steps":["trace[610710284] 'process raft request' (duration: 133.488371ms)","trace[610710284] 'compare' (duration: 16.18559ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:55.158418Z","caller":"traceutil/trace.go:171","msg":"trace[544830237] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"133.971283ms","start":"2026-08-22T15:29:55.024411Z","end":"2026-08-22T15:29:55.158383Z","steps":["trace[544830237] 'process raft request' (duration: 131.35573ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:29:55.308113Z","caller":"traceutil/trace.go:171","msg":"trace[1523967225] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"283.436098ms","start":"2026-08-22T15:29:55.024653Z","end":"2026-08-22T15:29:55.308089Z","steps":["trace[1523967225] 'process raft request' (duration: 259.306538ms)","trace[1523967225] 'compare' (duration: 23.943275ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:55.308304Z","caller":"traceutil/trace.go:171","msg":"trace[993386840] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25873; }","duration":"276.745871ms","start":"2026-08-22T15:29:55.031514Z","end":"2026-08-22T15:29:55.30826Z","steps":["trace[993386840] 'read index received' (duration: 124.353826ms)","trace[993386840] 'applied index is now lower than readState.Index' (duration: 152.390105ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:29:55.308391Z","caller":"traceutil/trace.go:171","msg":"trace[1409098375] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"282.215673ms","start":"2026-08-22T15:29:55.026145Z","end":"2026-08-22T15:29:55.308361Z","steps":["trace[1409098375] 'process raft request' (duration: 281.903705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:55.308532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.672074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:29:55.308631Z","caller":"traceutil/trace.go:171","msg":"trace[1663264696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25055; }","duration":"306.791657ms","start":"2026-08-22T15:29:55.001801Z","end":"2026-08-22T15:29:55.308592Z","steps":["trace[1663264696] 'agreement among raft nodes before linearized reading' (duration: 306.628763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:29:55.30868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:29:55.001782Z","time spent":"306.882989ms","remote":"127.0.0.1:54780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T15:29:55.502262Z","caller":"traceutil/trace.go:171","msg":"trace[643187951] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"125.18486ms","start":"2026-08-22T15:29:55.377033Z","end":"2026-08-22T15:29:55.502217Z","steps":["trace[643187951] 'process raft request' (duration: 111.260483ms)","trace[643187951] 'compare' (duration: 13.389042ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:03.576422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.734712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:30:03.576543Z","caller":"traceutil/trace.go:171","msg":"trace[1182835949] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"238.794487ms","start":"2026-08-22T15:30:03.337727Z","end":"2026-08-22T15:30:03.576521Z","steps":["trace[1182835949] 'process raft request' (duration: 115.8485ms)","trace[1182835949] 'compare' (duration: 122.270468ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:03.578848Z","caller":"traceutil/trace.go:171","msg":"trace[822239911] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"241.023899ms","start":"2026-08-22T15:30:03.337786Z","end":"2026-08-22T15:30:03.578809Z","steps":["trace[822239911] 'process raft request' (duration: 240.943736ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:03.893314Z","caller":"traceutil/trace.go:171","msg":"trace[1717195519] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"147.005235ms","start":"2026-08-22T15:30:03.746273Z","end":"2026-08-22T15:30:03.893278Z","steps":["trace[1717195519] 'process raft request' (duration: 91.747679ms)","trace[1717195519] 'compare' (duration: 55.04054ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:04.17808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.74289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-22T15:30:04.178122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.991757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:30:04.178181Z","caller":"traceutil/trace.go:171","msg":"trace[2044314342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25133; }","duration":"164.903604ms","start":"2026-08-22T15:30:04.013252Z","end":"2026-08-22T15:30:04.178156Z","steps":["trace[2044314342] 'range keys from in-memory index tree' (duration: 164.434181ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.178197Z","caller":"traceutil/trace.go:171","msg":"trace[570636744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25133; }","duration":"178.154632ms","start":"2026-08-22T15:30:04.000024Z","end":"2026-08-22T15:30:04.178179Z","steps":["trace[570636744] 'range keys from in-memory index tree' (duration: 177.878735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:04.178219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.358686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T15:30:04.178245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.194291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:30:04.178353Z","caller":"traceutil/trace.go:171","msg":"trace[227039443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25133; }","duration":"165.391527ms","start":"2026-08-22T15:30:04.012937Z","end":"2026-08-22T15:30:04.178328Z","steps":["trace[227039443] 'range keys from in-memory index tree' (duration: 164.949415ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.178358Z","caller":"traceutil/trace.go:171","msg":"trace[1455042187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25133; }","duration":"165.49905ms","start":"2026-08-22T15:30:04.012798Z","end":"2026-08-22T15:30:04.178297Z","steps":["trace[1455042187] 'range keys from in-memory index tree' (duration: 165.180141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:04.178397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.223255ms","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-08-22T15:30:04.178509Z","caller":"traceutil/trace.go:171","msg":"trace[840789515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25133; }","duration":"164.362758ms","start":"2026-08-22T15:30:04.014114Z","end":"2026-08-22T15:30:04.178477Z","steps":["trace[840789515] 'range keys from in-memory index tree' (duration: 164.04026ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.394358Z","caller":"traceutil/trace.go:171","msg":"trace[1508289485] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"210.784108ms","start":"2026-08-22T15:30:04.183552Z","end":"2026-08-22T15:30:04.394336Z","steps":["trace[1508289485] 'process raft request' (duration: 114.245815ms)","trace[1508289485] 'compare' (duration: 96.354018ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:04.394674Z","caller":"traceutil/trace.go:171","msg":"trace[2110983146] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"211.091216ms","start":"2026-08-22T15:30:04.183572Z","end":"2026-08-22T15:30:04.394663Z","steps":["trace[2110983146] 'process raft request' (duration: 210.723856ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.394705Z","caller":"traceutil/trace.go:171","msg":"trace[1481171309] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"211.086116ms","start":"2026-08-22T15:30:04.183592Z","end":"2026-08-22T15:30:04.394678Z","steps":["trace[1481171309] 'process raft request' (duration: 210.938412ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.448599Z","caller":"traceutil/trace.go:171","msg":"trace[1367024537] linearizableReadLoop","detail":"{readStateIndex:25962; appliedIndex:25958; }","duration":"172.977248ms","start":"2026-08-22T15:30:04.275592Z","end":"2026-08-22T15:30:04.448569Z","steps":["trace[1367024537] 'read index received' (duration: 22.27232ms)","trace[1367024537] 'applied index is now lower than readState.Index' (duration: 150.703478ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:04.448795Z","caller":"traceutil/trace.go:171","msg":"trace[1371827196] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"196.879791ms","start":"2026-08-22T15:30:04.251871Z","end":"2026-08-22T15:30:04.448751Z","steps":["trace[1371827196] 'process raft request' (duration: 196.489871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:04.44887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.250625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:30:04.448918Z","caller":"traceutil/trace.go:171","msg":"trace[773187589] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25139; }","duration":"173.329337ms","start":"2026-08-22T15:30:04.275571Z","end":"2026-08-22T15:30:04.448901Z","steps":["trace[773187589] 'agreement among raft nodes before linearized reading' (duration: 173.215094ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.469224Z","caller":"traceutil/trace.go:171","msg":"trace[1836593603] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"141.801322ms","start":"2026-08-22T15:30:04.327376Z","end":"2026-08-22T15:30:04.469177Z","steps":["trace[1836593603] 'process raft request' (duration: 141.141353ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:04.564004Z","caller":"traceutil/trace.go:171","msg":"trace[1007239820] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"109.210195ms","start":"2026-08-22T15:30:04.454748Z","end":"2026-08-22T15:30:04.563958Z","steps":["trace[1007239820] 'process raft request' (duration: 77.470354ms)","trace[1007239820] 'compare' (duration: 31.520455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:04.564164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.212157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18229"} {"level":"info","ts":"2026-08-22T15:30:04.564279Z","caller":"traceutil/trace.go:171","msg":"trace[1234575463] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25141; }","duration":"108.373072ms","start":"2026-08-22T15:30:04.455878Z","end":"2026-08-22T15:30:04.564251Z","steps":["trace[1234575463] 'agreement among raft nodes before linearized reading' (duration: 108.046002ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:09.787085Z","caller":"traceutil/trace.go:171","msg":"trace[364924610] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"188.497428ms","start":"2026-08-22T15:30:09.598567Z","end":"2026-08-22T15:30:09.787064Z","steps":["trace[364924610] 'process raft request' (duration: 188.326084ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:09.869189Z","caller":"traceutil/trace.go:171","msg":"trace[1337395802] linearizableReadLoop","detail":"{readStateIndex:26019; appliedIndex:26017; }","duration":"230.63369ms","start":"2026-08-22T15:30:09.638517Z","end":"2026-08-22T15:30:09.869151Z","steps":["trace[1337395802] 'read index received' (duration: 148.446146ms)","trace[1337395802] 'applied index is now lower than readState.Index' (duration: 82.185793ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:09.869302Z","caller":"traceutil/trace.go:171","msg":"trace[2070340638] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"268.781221ms","start":"2026-08-22T15:30:09.600481Z","end":"2026-08-22T15:30:09.869263Z","steps":["trace[2070340638] 'process raft request' (duration: 268.415431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:09.869396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.841655ms","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:8"} {"level":"info","ts":"2026-08-22T15:30:09.869447Z","caller":"traceutil/trace.go:171","msg":"trace[7895004] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25194; }","duration":"230.965489ms","start":"2026-08-22T15:30:09.638468Z","end":"2026-08-22T15:30:09.869433Z","steps":["trace[7895004] 'agreement among raft nodes before linearized reading' (duration: 230.838476ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:10.013596Z","caller":"traceutil/trace.go:171","msg":"trace[1866047791] linearizableReadLoop","detail":"{readStateIndex:26020; appliedIndex:26019; }","duration":"144.285091ms","start":"2026-08-22T15:30:09.869276Z","end":"2026-08-22T15:30:10.013561Z","steps":["trace[1866047791] 'read index received' (duration: 133.235283ms)","trace[1866047791] 'applied index is now lower than readState.Index' (duration: 11.048418ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:10.0137Z","caller":"traceutil/trace.go:171","msg":"trace[1293904235] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"185.500786ms","start":"2026-08-22T15:30:09.828128Z","end":"2026-08-22T15:30:10.013629Z","steps":["trace[1293904235] 'process raft request' (duration: 174.443698ms)","trace[1293904235] 'compare' (duration: 10.621706ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:10.013841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.238804ms","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":"warn","ts":"2026-08-22T15:30:10.01382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.803829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:30:10.01389Z","caller":"traceutil/trace.go:171","msg":"trace[1273479621] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25195; }","duration":"246.308146ms","start":"2026-08-22T15:30:09.767567Z","end":"2026-08-22T15:30:10.013875Z","steps":["trace[1273479621] 'agreement among raft nodes before linearized reading' (duration: 246.153192ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:10.013919Z","caller":"traceutil/trace.go:171","msg":"trace[759222346] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25195; }","duration":"188.977363ms","start":"2026-08-22T15:30:09.824918Z","end":"2026-08-22T15:30:10.013895Z","steps":["trace[759222346] 'agreement among raft nodes before linearized reading' (duration: 188.722856ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:10.165846Z","caller":"traceutil/trace.go:171","msg":"trace[113152048] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"120.072687ms","start":"2026-08-22T15:30:10.045743Z","end":"2026-08-22T15:30:10.165816Z","steps":["trace[113152048] 'process raft request' (duration: 120.000945ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:10.1659Z","caller":"traceutil/trace.go:171","msg":"trace[1230581374] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"146.743108ms","start":"2026-08-22T15:30:10.019104Z","end":"2026-08-22T15:30:10.165848Z","steps":["trace[1230581374] 'process raft request' (duration: 117.30114ms)","trace[1230581374] 'compare' (duration: 29.173551ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:10.365898Z","caller":"traceutil/trace.go:171","msg":"trace[2124270237] linearizableReadLoop","detail":"{readStateIndex:26023; appliedIndex:26022; }","duration":"155.436771ms","start":"2026-08-22T15:30:10.210426Z","end":"2026-08-22T15:30:10.365863Z","steps":["trace[2124270237] 'read index received' (duration: 88.739447ms)","trace[2124270237] 'applied index is now lower than readState.Index' (duration: 66.695764ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:10.365985Z","caller":"traceutil/trace.go:171","msg":"trace[739221495] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"164.812852ms","start":"2026-08-22T15:30:10.201121Z","end":"2026-08-22T15:30:10.365934Z","steps":["trace[739221495] 'process raft request' (duration: 98.130468ms)","trace[739221495] 'compare' (duration: 66.395025ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:10.366122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.673427ms","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-08-22T15:30:10.366164Z","caller":"traceutil/trace.go:171","msg":"trace[186297006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25198; }","duration":"155.788121ms","start":"2026-08-22T15:30:10.210365Z","end":"2026-08-22T15:30:10.366153Z","steps":["trace[186297006] 'agreement among raft nodes before linearized reading' (duration: 155.606626ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:14.289752Z","caller":"traceutil/trace.go:171","msg":"trace[1045428362] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"105.152192ms","start":"2026-08-22T15:30:14.184548Z","end":"2026-08-22T15:30:14.2897Z","steps":["trace[1045428362] 'process raft request' (duration: 104.765302ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:19.847191Z","caller":"traceutil/trace.go:171","msg":"trace[953879852] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"104.325489ms","start":"2026-08-22T15:30:19.742821Z","end":"2026-08-22T15:30:19.847147Z","steps":["trace[953879852] 'process raft request' (duration: 104.159295ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:26.15627Z","caller":"traceutil/trace.go:171","msg":"trace[1617256333] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26148; }","duration":"153.223929ms","start":"2026-08-22T15:30:26.003021Z","end":"2026-08-22T15:30:26.156245Z","steps":["trace[1617256333] 'read index received' (duration: 151.990615ms)","trace[1617256333] 'applied index is now lower than readState.Index' (duration: 1.231424ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:26.15629Z","caller":"traceutil/trace.go:171","msg":"trace[1801246446] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"197.841439ms","start":"2026-08-22T15:30:25.958418Z","end":"2026-08-22T15:30:26.156259Z","steps":["trace[1801246446] 'process raft request' (duration: 196.650616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:26.1564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.373023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:30:26.156427Z","caller":"traceutil/trace.go:171","msg":"trace[1028080996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25321; }","duration":"153.463046ms","start":"2026-08-22T15:30:26.002953Z","end":"2026-08-22T15:30:26.156416Z","steps":["trace[1028080996] 'agreement among raft nodes before linearized reading' (duration: 153.384414ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:30.758257Z","caller":"traceutil/trace.go:171","msg":"trace[107357714] linearizableReadLoop","detail":"{readStateIndex:26182; appliedIndex:26181; }","duration":"212.962419ms","start":"2026-08-22T15:30:30.545265Z","end":"2026-08-22T15:30:30.758227Z","steps":["trace[107357714] 'read index received' (duration: 212.791584ms)","trace[107357714] 'applied index is now lower than readState.Index' (duration: 169.165µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:30.758629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.34457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:30:30.75868Z","caller":"traceutil/trace.go:171","msg":"trace[280360862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25353; }","duration":"213.474343ms","start":"2026-08-22T15:30:30.545189Z","end":"2026-08-22T15:30:30.758663Z","steps":["trace[280360862] 'agreement among raft nodes before linearized reading' (duration: 213.193355ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:30.758729Z","caller":"traceutil/trace.go:171","msg":"trace[6757536] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"297.636852ms","start":"2026-08-22T15:30:30.460927Z","end":"2026-08-22T15:30:30.758564Z","steps":["trace[6757536] 'process raft request' (duration: 297.077337ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:30.820848Z","caller":"traceutil/trace.go:171","msg":"trace[1219696579] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"149.862875ms","start":"2026-08-22T15:30:30.670947Z","end":"2026-08-22T15:30:30.82081Z","steps":["trace[1219696579] 'process raft request' (duration: 149.585127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:30.821261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.781387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T15:30:30.8214Z","caller":"traceutil/trace.go:171","msg":"trace[1132082615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25354; }","duration":"170.937841ms","start":"2026-08-22T15:30:30.650386Z","end":"2026-08-22T15:30:30.821324Z","steps":["trace[1132082615] 'agreement among raft nodes before linearized reading' (duration: 170.703495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:30.821424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.335134ms","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-08-22T15:30:30.821516Z","caller":"traceutil/trace.go:171","msg":"trace[1059875561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25354; }","duration":"179.477579ms","start":"2026-08-22T15:30:30.642007Z","end":"2026-08-22T15:30:30.821485Z","steps":["trace[1059875561] 'agreement among raft nodes before linearized reading' (duration: 179.200571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:30.821417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.664446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:30:30.821822Z","caller":"traceutil/trace.go:171","msg":"trace[1326725290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25354; }","duration":"174.153561ms","start":"2026-08-22T15:30:30.647643Z","end":"2026-08-22T15:30:30.821796Z","steps":["trace[1326725290] 'agreement among raft nodes before linearized reading' (duration: 173.491272ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:36.310719Z","caller":"traceutil/trace.go:171","msg":"trace[10041245] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"111.679234ms","start":"2026-08-22T15:30:36.199001Z","end":"2026-08-22T15:30:36.31068Z","steps":["trace[10041245] 'process raft request' (duration: 74.650055ms)","trace[10041245] 'compare' (duration: 36.793962ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:36.335771Z","caller":"traceutil/trace.go:171","msg":"trace[69952131] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"120.332114ms","start":"2026-08-22T15:30:36.215382Z","end":"2026-08-22T15:30:36.335714Z","steps":["trace[69952131] 'process raft request' (duration: 120.022775ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:36.644473Z","caller":"traceutil/trace.go:171","msg":"trace[1768963051] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"141.444201ms","start":"2026-08-22T15:30:36.502971Z","end":"2026-08-22T15:30:36.644415Z","steps":["trace[1768963051] 'process raft request' (duration: 141.175093ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.065448Z","caller":"traceutil/trace.go:171","msg":"trace[183334462] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"196.046629ms","start":"2026-08-22T15:30:36.869367Z","end":"2026-08-22T15:30:37.065414Z","steps":["trace[183334462] 'process raft request' (duration: 174.11809ms)","trace[183334462] 'compare' (duration: 21.782445ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:37.070949Z","caller":"traceutil/trace.go:171","msg":"trace[989984375] linearizableReadLoop","detail":"{readStateIndex:26243; appliedIndex:26240; }","duration":"191.247445ms","start":"2026-08-22T15:30:36.879671Z","end":"2026-08-22T15:30:37.070919Z","steps":["trace[989984375] 'read index received' (duration: 163.839434ms)","trace[989984375] 'applied index is now lower than readState.Index' (duration: 27.406871ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:37.071006Z","caller":"traceutil/trace.go:171","msg":"trace[314067285] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"201.274924ms","start":"2026-08-22T15:30:36.869688Z","end":"2026-08-22T15:30:37.070963Z","steps":["trace[314067285] 'process raft request' (duration: 201.047878ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.071096Z","caller":"traceutil/trace.go:171","msg":"trace[1460703409] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"200.713197ms","start":"2026-08-22T15:30:36.870366Z","end":"2026-08-22T15:30:37.071079Z","steps":["trace[1460703409] 'process raft request' (duration: 200.509732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:37.071471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.754669ms","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-08-22T15:30:37.07156Z","caller":"traceutil/trace.go:171","msg":"trace[166130564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25413; }","duration":"192.068058ms","start":"2026-08-22T15:30:36.879468Z","end":"2026-08-22T15:30:37.071537Z","steps":["trace[166130564] 'agreement among raft nodes before linearized reading' (duration: 191.748148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:37.137802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.765343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:30:37.137876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.340493ms","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":"warn","ts":"2026-08-22T15:30:37.137906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.672338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:30:37.137919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.032386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:30:37.137986Z","caller":"traceutil/trace.go:171","msg":"trace[1970203066] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25414; }","duration":"257.523017ms","start":"2026-08-22T15:30:36.880433Z","end":"2026-08-22T15:30:37.137956Z","steps":["trace[1970203066] 'agreement among raft nodes before linearized reading' (duration: 257.200179ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.137995Z","caller":"traceutil/trace.go:171","msg":"trace[995757926] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25414; }","duration":"227.837372ms","start":"2026-08-22T15:30:36.910132Z","end":"2026-08-22T15:30:37.13797Z","steps":["trace[995757926] 'agreement among raft nodes before linearized reading' (duration: 227.651287ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.137999Z","caller":"traceutil/trace.go:171","msg":"trace[1821659263] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25414; }","duration":"156.224542ms","start":"2026-08-22T15:30:36.981757Z","end":"2026-08-22T15:30:37.137981Z","steps":["trace[1821659263] 'agreement among raft nodes before linearized reading' (duration: 156.055117ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.137901Z","caller":"traceutil/trace.go:171","msg":"trace[1426807179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25414; }","duration":"135.952918ms","start":"2026-08-22T15:30:37.001926Z","end":"2026-08-22T15:30:37.137879Z","steps":["trace[1426807179] 'agreement among raft nodes before linearized reading' (duration: 135.763733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:37.138173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.901571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-08-22T15:30:37.138469Z","caller":"traceutil/trace.go:171","msg":"trace[84688680] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25414; }","duration":"161.256251ms","start":"2026-08-22T15:30:36.977046Z","end":"2026-08-22T15:30:37.138302Z","steps":["trace[84688680] 'agreement among raft nodes before linearized reading' (duration: 160.695055ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.287482Z","caller":"traceutil/trace.go:171","msg":"trace[2019273222] linearizableReadLoop","detail":"{readStateIndex:26245; appliedIndex:26244; }","duration":"147.666344ms","start":"2026-08-22T15:30:37.139789Z","end":"2026-08-22T15:30:37.287455Z","steps":["trace[2019273222] 'read index received' (duration: 74.593733ms)","trace[2019273222] 'applied index is now lower than readState.Index' (duration: 73.071461ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:30:37.287584Z","caller":"traceutil/trace.go:171","msg":"trace[929752335] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"210.800129ms","start":"2026-08-22T15:30:37.076727Z","end":"2026-08-22T15:30:37.287527Z","steps":["trace[929752335] 'process raft request' (duration: 137.630666ms)","trace[929752335] 'compare' (duration: 72.855294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:30:37.287727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.881051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:30:37.287803Z","caller":"traceutil/trace.go:171","msg":"trace[1243984154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25415; }","duration":"148.051275ms","start":"2026-08-22T15:30:37.139739Z","end":"2026-08-22T15:30:37.28779Z","steps":["trace[1243984154] 'agreement among raft nodes before linearized reading' (duration: 147.883811ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:37.313023Z","caller":"traceutil/trace.go:171","msg":"trace[1112567974] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"170.769636ms","start":"2026-08-22T15:30:37.142233Z","end":"2026-08-22T15:30:37.313003Z","steps":["trace[1112567974] 'process raft request' (duration: 170.505529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:37.313105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.450395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2f90ca38-9d7d-49ad-ab62-01d4f92a4833\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-08-22T15:30:37.313183Z","caller":"traceutil/trace.go:171","msg":"trace[582940771] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2f90ca38-9d7d-49ad-ab62-01d4f92a4833; range_end:; response_count:1; response_revision:25416; }","duration":"171.546257ms","start":"2026-08-22T15:30:37.141616Z","end":"2026-08-22T15:30:37.313162Z","steps":["trace[582940771] 'agreement among raft nodes before linearized reading' (duration: 171.297391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:30:37.765979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.743244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:30:37.766137Z","caller":"traceutil/trace.go:171","msg":"trace[1557092689] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25418; }","duration":"112.941079ms","start":"2026-08-22T15:30:37.653173Z","end":"2026-08-22T15:30:37.766115Z","steps":["trace[1557092689] 'count revisions from in-memory index tree' (duration: 112.641031ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:30:56.791687Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067a3.wal"} {"level":"info","ts":"2026-08-22T15:31:24.412341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22935} {"level":"info","ts":"2026-08-22T15:31:24.549495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22935,"took":"132.016549ms","hash":2160991597,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":35573760,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-22T15:31:24.549584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2160991597,"revision":22935,"compact-revision":20036} {"level":"info","ts":"2026-08-22T15:36:24.420988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25933} {"level":"info","ts":"2026-08-22T15:36:24.5715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25933,"took":"145.241887ms","hash":1977321320,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":34721792,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T15:36:24.571653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1977321320,"revision":25933,"compact-revision":22935} {"level":"info","ts":"2026-08-22T15:37:25.380574Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:37:25.386Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T15:37:25.386139Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-22T15:41:24.427969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28630} {"level":"info","ts":"2026-08-22T15:41:24.577313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28630,"took":"143.491668ms","hash":1787273683,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T15:41:24.577423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1787273683,"revision":28630,"compact-revision":25933} {"level":"info","ts":"2026-08-22T15:46:24.436094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30848} {"level":"info","ts":"2026-08-22T15:46:24.559335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30848,"took":"119.106066ms","hash":1619743148,"current-db-size-bytes":58867712,"current-db-size":"59 MB","current-db-size-in-use-bytes":31498240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:46:24.559417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1619743148,"revision":30848,"compact-revision":28630} {"level":"info","ts":"2026-08-22T15:51:24.442968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33114} {"level":"info","ts":"2026-08-22T15:51:24.59676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33114,"took":"147.727794ms","hash":76966582,"current-db-size-bytes":59908096,"current-db-size":"60 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:51:24.596819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":76966582,"revision":33114,"compact-revision":30848} {"level":"info","ts":"2026-08-22T15:56:24.449682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35406} {"level":"info","ts":"2026-08-22T15:56:24.595457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35406,"took":"140.32414ms","hash":908292657,"current-db-size-bytes":59908096,"current-db-size":"60 MB","current-db-size-in-use-bytes":32104448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:56:24.595555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":908292657,"revision":35406,"compact-revision":33114} {"level":"info","ts":"2026-08-22T15:58:57.578939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:58:57.584001Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-22T15:58:57.584122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-22T16:01:24.457279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37632} {"level":"info","ts":"2026-08-22T16:01:24.578796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37632,"took":"115.981898ms","hash":243695006,"current-db-size-bytes":60329984,"current-db-size":"60 MB","current-db-size-in-use-bytes":32440320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T16:01:24.578878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":243695006,"revision":37632,"compact-revision":35406}