{"level":"warn","ts":"2026-07-22T22:03:04.705374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:03:04.705565Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-22T22:03:04.70576Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-22T22:03:04.705803Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-07-22T22:03:04.70589Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T22:03:04.706672Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-07-22T22:03:04.706852Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-22T22:03:04.710298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.16185ms"} {"level":"info","ts":"2026-07-22T22:03:04.715819Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-07-22T22:03:04.716033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-07-22T22:03:04.716101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-07-22T22:03:04.71615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-22T22:03:04.716194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-07-22T22:03:04.716283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-07-22T22:03:04.725513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-22T22:03:04.727052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-22T22:03:04.728465Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-22T22:03:04.731777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-22T22:03:04.732323Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-22T22:03:04.732372Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:03:04.732574Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:03:04.732612Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-22T22:03:04.734562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-07-22T22:03:04.735716Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-07-22T22:03:04.737947Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-22T22:03:04.738109Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-22T22:03:04.738131Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-22T22:03:04.738184Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-07-22T22:03:04.738196Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-07-22T22:03:05.717236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-07-22T22:03:05.717329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-22T22:03:05.717362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-07-22T22:03:05.717383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-07-22T22:03:05.717394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-07-22T22:03:05.71741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-07-22T22:03:05.71743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-07-22T22:03:05.718432Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-07-22T22:03:05.718474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:03:05.718789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:03:05.719142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-22T22:03:05.719487Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-22T22:03:05.719557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-22T22:03:05.719702Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:03:05.719806Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:03:05.719866Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-22T22:03:05.722594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-22T22:03:05.723276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-07-22T22:03:08.113928Z","caller":"traceutil/trace.go:171","msg":"trace[2039849077] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"139.384734ms","start":"2026-07-22T22:03:07.97452Z","end":"2026-07-22T22:03:08.113905Z","steps":["trace[2039849077] 'read index received' (duration: 139.202833ms)","trace[2039849077] 'applied index is now lower than readState.Index' (duration: 180.661µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:08.114008Z","caller":"traceutil/trace.go:171","msg":"trace[1030850452] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"141.273803ms","start":"2026-07-22T22:03:07.972704Z","end":"2026-07-22T22:03:08.113978Z","steps":["trace[1030850452] 'process raft request' (duration: 141.065151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:08.114169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.623547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T22:03:08.114237Z","caller":"traceutil/trace.go:171","msg":"trace[866533862] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:112; }","duration":"139.713788ms","start":"2026-07-22T22:03:07.97451Z","end":"2026-07-22T22:03:08.114224Z","steps":["trace[866533862] 'agreement among raft nodes before linearized reading' (duration: 139.548776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:08.549551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.78966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-22T22:03:08.549664Z","caller":"traceutil/trace.go:171","msg":"trace[595197873] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"428.287654ms","start":"2026-07-22T22:03:08.121363Z","end":"2026-07-22T22:03:08.549651Z","steps":["trace[595197873] 'read index received' (duration: 185.969268ms)","trace[595197873] 'applied index is now lower than readState.Index' (duration: 242.317326ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:08.549715Z","caller":"traceutil/trace.go:171","msg":"trace[686453421] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"429.768567ms","start":"2026-07-22T22:03:08.119907Z","end":"2026-07-22T22:03:08.549676Z","steps":["trace[686453421] 'process raft request' (duration: 187.497102ms)","trace[686453421] 'compare' (duration: 241.660119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:08.549862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.492855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-22T22:03:08.549925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:08.119887Z","time spent":"429.935319ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:03:08.549999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.800937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T22:03:08.550051Z","caller":"traceutil/trace.go:171","msg":"trace[53080828] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:113; }","duration":"290.896819ms","start":"2026-07-22T22:03:08.259145Z","end":"2026-07-22T22:03:08.550042Z","steps":["trace[53080828] 'agreement among raft nodes before linearized reading' (duration: 290.828198ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:08.549951Z","caller":"traceutil/trace.go:171","msg":"trace[650029334] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:113; }","duration":"428.590436ms","start":"2026-07-22T22:03:08.121331Z","end":"2026-07-22T22:03:08.549921Z","steps":["trace[650029334] 'agreement among raft nodes before linearized reading' (duration: 428.416695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:08.550098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:08.121324Z","time spent":"428.757367ms","remote":"127.0.0.1:50998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-07-22T22:03:08.677132Z","caller":"traceutil/trace.go:171","msg":"trace[1958122115] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"123.00085ms","start":"2026-07-22T22:03:08.554105Z","end":"2026-07-22T22:03:08.677106Z","steps":["trace[1958122115] 'read index received' (duration: 122.800548ms)","trace[1958122115] 'applied index is now lower than readState.Index' (duration: 198.842µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:08.677179Z","caller":"traceutil/trace.go:171","msg":"trace[1951558343] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"124.561855ms","start":"2026-07-22T22:03:08.552582Z","end":"2026-07-22T22:03:08.677144Z","steps":["trace[1951558343] 'process raft request' (duration: 124.390032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:08.677294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.155961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-22T22:03:08.677337Z","caller":"traceutil/trace.go:171","msg":"trace[1442018484] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:114; }","duration":"123.258952ms","start":"2026-07-22T22:03:08.554067Z","end":"2026-07-22T22:03:08.677326Z","steps":["trace[1442018484] 'agreement among raft nodes before linearized reading' (duration: 123.134861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:42.097266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.918274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"warn","ts":"2026-07-22T22:03:42.097303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.47751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-07-22T22:03:42.09732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.387767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-07-22T22:03:42.097357Z","caller":"traceutil/trace.go:171","msg":"trace[1813799135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:524; }","duration":"127.562823ms","start":"2026-07-22T22:03:41.96978Z","end":"2026-07-22T22:03:42.097343Z","steps":["trace[1813799135] 'range keys from in-memory index tree' (duration: 127.393407ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:42.097357Z","caller":"traceutil/trace.go:171","msg":"trace[1307514275] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:524; }","duration":"205.047868ms","start":"2026-07-22T22:03:41.89229Z","end":"2026-07-22T22:03:42.097338Z","steps":["trace[1307514275] 'range keys from in-memory index tree' (duration: 204.84268ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:42.09739Z","caller":"traceutil/trace.go:171","msg":"trace[353708244] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:524; }","duration":"127.508861ms","start":"2026-07-22T22:03:41.969862Z","end":"2026-07-22T22:03:42.097371Z","steps":["trace[353708244] 'range keys from in-memory index tree' (duration: 127.249801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:44.429934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.403486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:03:44.430061Z","caller":"traceutil/trace.go:171","msg":"trace[865970363] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"519.884564ms","start":"2026-07-22T22:03:43.910156Z","end":"2026-07-22T22:03:44.430041Z","steps":["trace[865970363] 'process raft request' (duration: 332.123205ms)","trace[865970363] 'compare' (duration: 187.256099ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:44.430126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:43.91007Z","time spent":"520.02723ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:03:44.457416Z","caller":"traceutil/trace.go:171","msg":"trace[483184210] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"485.349167ms","start":"2026-07-22T22:03:43.972033Z","end":"2026-07-22T22:03:44.457382Z","steps":["trace[483184210] 'read index received' (duration: 270.23967ms)","trace[483184210] 'applied index is now lower than readState.Index' (duration: 215.108237ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:44.457458Z","caller":"traceutil/trace.go:171","msg":"trace[656746028] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"487.833985ms","start":"2026-07-22T22:03:43.969591Z","end":"2026-07-22T22:03:44.457425Z","steps":["trace[656746028] 'process raft request' (duration: 487.581715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:44.457609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:43.969564Z","time spent":"487.972231ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:03:44.457822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.762034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:150495"} {"level":"info","ts":"2026-07-22T22:03:44.457884Z","caller":"traceutil/trace.go:171","msg":"trace[860240954] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:582; }","duration":"485.885238ms","start":"2026-07-22T22:03:43.971979Z","end":"2026-07-22T22:03:44.457864Z","steps":["trace[860240954] 'agreement among raft nodes before linearized reading' (duration: 485.553105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:44.457935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:43.97197Z","time spent":"485.953361ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":150518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-22T22:03:44.487781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.62302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:03:44.488268Z","caller":"traceutil/trace.go:171","msg":"trace[1454904458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"409.756456ms","start":"2026-07-22T22:03:44.078092Z","end":"2026-07-22T22:03:44.487848Z","steps":["trace[1454904458] 'agreement among raft nodes before linearized reading' (duration: 409.60439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:44.48877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:44.078074Z","time spent":"410.617889ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-22T22:03:44.662528Z","caller":"traceutil/trace.go:171","msg":"trace[2077294955] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"169.5768ms","start":"2026-07-22T22:03:44.492925Z","end":"2026-07-22T22:03:44.662502Z","steps":["trace[2077294955] 'read index received' (duration: 102.400718ms)","trace[2077294955] 'applied index is now lower than readState.Index' (duration: 67.174782ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:44.662647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.700635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:03:44.662668Z","caller":"traceutil/trace.go:171","msg":"trace[913738193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"169.773227ms","start":"2026-07-22T22:03:44.492889Z","end":"2026-07-22T22:03:44.662662Z","steps":["trace[913738193] 'agreement among raft nodes before linearized reading' (duration: 169.691964ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:44.662789Z","caller":"traceutil/trace.go:171","msg":"trace[103755912] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"187.199368ms","start":"2026-07-22T22:03:44.475547Z","end":"2026-07-22T22:03:44.662746Z","steps":["trace[103755912] 'process raft request' (duration: 119.710523ms)","trace[103755912] 'compare' (duration: 67.124391ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:44.847879Z","caller":"traceutil/trace.go:171","msg":"trace[249319681] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"334.749457ms","start":"2026-07-22T22:03:44.513111Z","end":"2026-07-22T22:03:44.84786Z","steps":["trace[249319681] 'process raft request' (duration: 333.147645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:44.848002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:44.513074Z","time spent":"334.870592ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:03:44.851091Z","caller":"traceutil/trace.go:171","msg":"trace[1596809868] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"176.373105ms","start":"2026-07-22T22:03:44.674705Z","end":"2026-07-22T22:03:44.851078Z","steps":["trace[1596809868] 'process raft request' (duration: 176.061392ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:44.851217Z","caller":"traceutil/trace.go:171","msg":"trace[1621229455] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"133.110886ms","start":"2026-07-22T22:03:44.718078Z","end":"2026-07-22T22:03:44.851189Z","steps":["trace[1621229455] 'process raft request' (duration: 132.835895ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:44.85882Z","caller":"traceutil/trace.go:171","msg":"trace[2031715770] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"175.255012ms","start":"2026-07-22T22:03:44.683537Z","end":"2026-07-22T22:03:44.858792Z","steps":["trace[2031715770] 'read index received' (duration: 162.658931ms)","trace[2031715770] 'applied index is now lower than readState.Index' (duration: 12.594731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:44.858962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.45793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:03:44.85901Z","caller":"traceutil/trace.go:171","msg":"trace[762186321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"175.561243ms","start":"2026-07-22T22:03:44.68344Z","end":"2026-07-22T22:03:44.859001Z","steps":["trace[762186321] 'agreement among raft nodes before linearized reading' (duration: 175.444039ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:50.60732Z","caller":"traceutil/trace.go:171","msg":"trace[933136209] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"134.83318ms","start":"2026-07-22T22:03:50.472466Z","end":"2026-07-22T22:03:50.6073Z","steps":["trace[933136209] 'process raft request' (duration: 45.853089ms)","trace[933136209] 'compare' (duration: 88.840145ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:51.291272Z","caller":"traceutil/trace.go:171","msg":"trace[1229870942] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"201.119562ms","start":"2026-07-22T22:03:51.090119Z","end":"2026-07-22T22:03:51.291238Z","steps":["trace[1229870942] 'process raft request' (duration: 201.082851ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:51.291339Z","caller":"traceutil/trace.go:171","msg":"trace[1332123921] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"218.391359ms","start":"2026-07-22T22:03:51.072913Z","end":"2026-07-22T22:03:51.291304Z","steps":["trace[1332123921] 'process raft request' (duration: 133.820582ms)","trace[1332123921] 'compare' (duration: 84.347528ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:51.291608Z","caller":"traceutil/trace.go:171","msg":"trace[1289026482] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"215.695161ms","start":"2026-07-22T22:03:51.075899Z","end":"2026-07-22T22:03:51.291594Z","steps":["trace[1289026482] 'read index received' (duration: 130.976759ms)","trace[1289026482] 'applied index is now lower than readState.Index' (duration: 84.716902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:51.291728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.816495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:03:51.291766Z","caller":"traceutil/trace.go:171","msg":"trace[2113927782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"215.894918ms","start":"2026-07-22T22:03:51.075857Z","end":"2026-07-22T22:03:51.291752Z","steps":["trace[2113927782] 'agreement among raft nodes before linearized reading' (duration: 215.788334ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:51.799499Z","caller":"traceutil/trace.go:171","msg":"trace[2055694498] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"486.639785ms","start":"2026-07-22T22:03:51.312832Z","end":"2026-07-22T22:03:51.799472Z","steps":["trace[2055694498] 'read index received' (duration: 432.047466ms)","trace[2055694498] 'applied index is now lower than readState.Index' (duration: 54.588879ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:51.799831Z","caller":"traceutil/trace.go:171","msg":"trace[102249976] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"489.508789ms","start":"2026-07-22T22:03:51.31029Z","end":"2026-07-22T22:03:51.799799Z","steps":["trace[102249976] 'process raft request' (duration: 434.65764ms)","trace[102249976] 'compare' (duration: 54.365841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:51.799965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.508541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-22T22:03:51.799981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.119903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jnkhb\" ","response":"range_response_count:1 size:16957"} {"level":"warn","ts":"2026-07-22T22:03:51.800031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.310277Z","time spent":"489.616813ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:03:51.800063Z","caller":"traceutil/trace.go:171","msg":"trace[1947023826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"371.665377ms","start":"2026-07-22T22:03:51.428377Z","end":"2026-07-22T22:03:51.800042Z","steps":["trace[1947023826] 'agreement among raft nodes before linearized reading' (duration: 371.368417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:51.800112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.428354Z","time spent":"371.749039ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-22T22:03:51.800136Z","caller":"traceutil/trace.go:171","msg":"trace[2065929714] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jnkhb; range_end:; response_count:1; response_revision:645; }","duration":"487.268318ms","start":"2026-07-22T22:03:51.312798Z","end":"2026-07-22T22:03:51.800066Z","steps":["trace[2065929714] 'agreement among raft nodes before linearized reading' (duration: 486.964477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:51.8002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.312781Z","time spent":"487.403443ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-jnkhb\" "} {"level":"warn","ts":"2026-07-22T22:03:51.800607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.236381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T22:03:51.80066Z","caller":"traceutil/trace.go:171","msg":"trace[337555697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"189.327704ms","start":"2026-07-22T22:03:51.611321Z","end":"2026-07-22T22:03:51.800648Z","steps":["trace[337555697] 'agreement among raft nodes before linearized reading' (duration: 188.445902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:52.578342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312882366812545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-22T22:03:52.93096Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126207112s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-22T22:03:53.01515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.05771ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:03:53.015269Z","caller":"traceutil/trace.go:171","msg":"trace[769069314] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:645; }","duration":"870.185504ms","start":"2026-07-22T22:03:52.145065Z","end":"2026-07-22T22:03:53.01525Z","steps":["trace[769069314] 'range keys from in-memory index tree' (duration: 870.041579ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:53.015451Z","caller":"traceutil/trace.go:171","msg":"trace[2019739345] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.21073159s","start":"2026-07-22T22:03:51.804676Z","end":"2026-07-22T22:03:53.015408Z","steps":["trace[2019739345] 'process raft request' (duration: 1.126521423s)","trace[2019739345] 'compare' (duration: 83.602646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:03:53.015562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.804656Z","time spent":"1.210850504s","remote":"127.0.0.1:50690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-22T22:03:53.017448Z","caller":"traceutil/trace.go:171","msg":"trace[1500033798] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.212352258s","start":"2026-07-22T22:03:51.805066Z","end":"2026-07-22T22:03:53.017418Z","steps":["trace[1500033798] 'process raft request' (duration: 1.212087549s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:53.017608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.805041Z","time spent":"1.212474512s","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:03:53.017942Z","caller":"traceutil/trace.go:171","msg":"trace[155000732] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.210173579s","start":"2026-07-22T22:03:51.807735Z","end":"2026-07-22T22:03:53.017909Z","steps":["trace[155000732] 'process raft request' (duration: 1.209635609s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:53.018091Z","caller":"traceutil/trace.go:171","msg":"trace[1143218091] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.21217641s","start":"2026-07-22T22:03:51.805907Z","end":"2026-07-22T22:03:53.018083Z","steps":["trace[1143218091] 'process raft request' (duration: 1.211409213s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:53.018209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.807715Z","time spent":"1.210383975s","remote":"127.0.0.1:50904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:03:53.018222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.805901Z","time spent":"1.212225282s","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:03:53.018031Z","caller":"traceutil/trace.go:171","msg":"trace[1400339316] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.212207442s","start":"2026-07-22T22:03:51.805801Z","end":"2026-07-22T22:03:53.018008Z","steps":["trace[1400339316] 'process raft request' (duration: 1.211478026s)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:53.018636Z","caller":"traceutil/trace.go:171","msg":"trace[376741005] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.20332513s","start":"2026-07-22T22:03:51.815297Z","end":"2026-07-22T22:03:53.018622Z","steps":["trace[376741005] 'process raft request' (duration: 1.203158614s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:53.018655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.805792Z","time spent":"1.212828845s","remote":"127.0.0.1:50690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-22T22:03:53.018709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.81525Z","time spent":"1.203426683s","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:03:53.018819Z","caller":"traceutil/trace.go:171","msg":"trace[1590623817] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:670; }","duration":"940.956703ms","start":"2026-07-22T22:03:52.077844Z","end":"2026-07-22T22:03:53.018801Z","steps":["trace[1590623817] 'read index received' (duration: 853.393056ms)","trace[1590623817] 'applied index is now lower than readState.Index' (duration: 87.562537ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:03:53.018853Z","caller":"traceutil/trace.go:171","msg":"trace[1236965195] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.21000325s","start":"2026-07-22T22:03:51.808837Z","end":"2026-07-22T22:03:53.01884Z","steps":["trace[1236965195] 'process raft request' (duration: 1.208788137s)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:53.018914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:51.808826Z","time spent":"1.210052263s","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:03:53.019047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.203381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:03:53.019095Z","caller":"traceutil/trace.go:171","msg":"trace[1464361424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"941.284844ms","start":"2026-07-22T22:03:52.077798Z","end":"2026-07-22T22:03:53.019083Z","steps":["trace[1464361424] 'agreement among raft nodes before linearized reading' (duration: 941.18718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:53.019124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:52.07778Z","time spent":"941.338257ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:03:53.019203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.781499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:03:53.019248Z","caller":"traceutil/trace.go:171","msg":"trace[2071461017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"108.859662ms","start":"2026-07-22T22:03:52.910376Z","end":"2026-07-22T22:03:53.019236Z","steps":["trace[2071461017] 'agreement among raft nodes before linearized reading' (duration: 108.705466ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:03:53.019513Z","caller":"traceutil/trace.go:171","msg":"trace[501357873] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"679.391835ms","start":"2026-07-22T22:03:52.340109Z","end":"2026-07-22T22:03:53.019501Z","steps":["trace[501357873] 'process raft request' (duration: 678.460232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:03:53.019587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:03:52.340085Z","time spent":"679.465497ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:03:54.745706Z","caller":"traceutil/trace.go:171","msg":"trace[807798789] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"277.141854ms","start":"2026-07-22T22:03:54.468538Z","end":"2026-07-22T22:03:54.74568Z","steps":["trace[807798789] 'process raft request' (duration: 277.015839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.865609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.135958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-22T22:04:07.86567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.480685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:04:07.865721Z","caller":"traceutil/trace.go:171","msg":"trace[931191630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"757.541808ms","start":"2026-07-22T22:04:07.108163Z","end":"2026-07-22T22:04:07.865705Z","steps":["trace[931191630] 'range keys from in-memory index tree' (duration: 757.409284ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:07.865723Z","caller":"traceutil/trace.go:171","msg":"trace[668355308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"759.279202ms","start":"2026-07-22T22:04:07.106423Z","end":"2026-07-22T22:04:07.865702Z","steps":["trace[668355308] 'range keys from in-memory index tree' (duration: 758.949371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.865772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:07.106414Z","time spent":"759.344293ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:04:07.865759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:07.108157Z","time spent":"757.594169ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-22T22:04:07.865939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.569531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-22T22:04:07.866032Z","caller":"traceutil/trace.go:171","msg":"trace[1072484378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"508.697965ms","start":"2026-07-22T22:04:07.357318Z","end":"2026-07-22T22:04:07.866016Z","steps":["trace[1072484378] 'range keys from in-memory index tree' (duration: 508.401566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.866022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.523939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-22T22:04:07.866098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:07.357294Z","time spent":"508.791048ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T22:04:07.866125Z","caller":"traceutil/trace.go:171","msg":"trace[199919275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"759.614931ms","start":"2026-07-22T22:04:07.106484Z","end":"2026-07-22T22:04:07.866099Z","steps":["trace[199919275] 'range keys from in-memory index tree' (duration: 759.437517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.866204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:07.106469Z","time spent":"759.701345ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-22T22:04:07.866015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.921107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-22T22:04:07.866375Z","caller":"traceutil/trace.go:171","msg":"trace[2107614021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"788.31689ms","start":"2026-07-22T22:04:07.078042Z","end":"2026-07-22T22:04:07.866358Z","steps":["trace[2107614021] 'range keys from in-memory index tree' (duration: 787.774983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.866433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:07.078029Z","time spent":"788.389652ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-22T22:04:07.867802Z","caller":"traceutil/trace.go:171","msg":"trace[1232074047] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"264.772785ms","start":"2026-07-22T22:04:07.603008Z","end":"2026-07-22T22:04:07.86778Z","steps":["trace[1232074047] 'read index received' (duration: 264.568979ms)","trace[1232074047] 'applied index is now lower than readState.Index' (duration: 202.796µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:04:07.868051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.008952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:04:07.868105Z","caller":"traceutil/trace.go:171","msg":"trace[705566185] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:802; }","duration":"265.125545ms","start":"2026-07-22T22:04:07.602962Z","end":"2026-07-22T22:04:07.868088Z","steps":["trace[705566185] 'agreement among raft nodes before linearized reading' (duration: 264.986891ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:04:07.868273Z","caller":"traceutil/trace.go:171","msg":"trace[1193613812] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"447.217472ms","start":"2026-07-22T22:04:07.421044Z","end":"2026-07-22T22:04:07.868261Z","steps":["trace[1193613812] 'process raft request' (duration: 446.601383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.868324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.086861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:04:07.868379Z","caller":"traceutil/trace.go:171","msg":"trace[888928618] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:802; }","duration":"114.151243ms","start":"2026-07-22T22:04:07.754213Z","end":"2026-07-22T22:04:07.868365Z","steps":["trace[888928618] 'agreement among raft nodes before linearized reading' (duration: 113.906215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:07.868386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:07.421025Z","time spent":"447.282175ms","remote":"127.0.0.1:50904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:04:37.525107Z","caller":"traceutil/trace.go:171","msg":"trace[728155316] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"373.306203ms","start":"2026-07-22T22:04:37.151768Z","end":"2026-07-22T22:04:37.525074Z","steps":["trace[728155316] 'process raft request' (duration: 373.174209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:04:37.525273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:04:37.151743Z","time spent":"373.451815ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:04:58.87082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.11937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:04:58.870916Z","caller":"traceutil/trace.go:171","msg":"trace[1143803631] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1460; }","duration":"119.282873ms","start":"2026-07-22T22:04:58.751615Z","end":"2026-07-22T22:04:58.870898Z","steps":["trace[1143803631] 'count revisions from in-memory index tree' (duration: 119.025059ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:22.579019Z","caller":"traceutil/trace.go:171","msg":"trace[2137206822] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"340.701973ms","start":"2026-07-22T22:05:22.238285Z","end":"2026-07-22T22:05:22.578987Z","steps":["trace[2137206822] 'read index received' (duration: 340.548521ms)","trace[2137206822] 'applied index is now lower than readState.Index' (duration: 152.542µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:05:22.579118Z","caller":"traceutil/trace.go:171","msg":"trace[509460182] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"350.201885ms","start":"2026-07-22T22:05:22.228872Z","end":"2026-07-22T22:05:22.579074Z","steps":["trace[509460182] 'process raft request' (duration: 349.942131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:05:22.579315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.228847Z","time spent":"350.368317ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:05:22.598529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.927495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c406aa3b-fa24-41e4-aad2-cb7a56b7ad52\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-07-22T22:05:22.598626Z","caller":"traceutil/trace.go:171","msg":"trace[2051043201] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c406aa3b-fa24-41e4-aad2-cb7a56b7ad52; range_end:; response_count:1; response_revision:1864; }","duration":"268.077197ms","start":"2026-07-22T22:05:22.330526Z","end":"2026-07-22T22:05:22.598603Z","steps":["trace[2051043201] 'agreement among raft nodes before linearized reading' (duration: 248.637454ms)","trace[2051043201] 'range keys from in-memory index tree' (duration: 19.138107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:22.598646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.807141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-22T22:05:22.598697Z","caller":"traceutil/trace.go:171","msg":"trace[1224812574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1864; }","duration":"129.887343ms","start":"2026-07-22T22:05:22.468785Z","end":"2026-07-22T22:05:22.598673Z","steps":["trace[1224812574] 'agreement among raft nodes before linearized reading' (duration: 110.38999ms)","trace[1224812574] 'range keys from in-memory index tree' (duration: 19.383622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:22.59854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.243565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-22T22:05:22.598896Z","caller":"traceutil/trace.go:171","msg":"trace[1961764694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1864; }","duration":"355.598672ms","start":"2026-07-22T22:05:22.243251Z","end":"2026-07-22T22:05:22.598849Z","steps":["trace[1961764694] 'agreement among raft nodes before linearized reading' (duration: 335.921006ms)","trace[1961764694] 'range keys from in-memory index tree' (duration: 19.251569ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:22.598987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.696286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-22T22:05:22.599075Z","caller":"traceutil/trace.go:171","msg":"trace[1380262593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1864; }","duration":"360.795157ms","start":"2026-07-22T22:05:22.23826Z","end":"2026-07-22T22:05:22.599055Z","steps":["trace[1380262593] 'agreement among raft nodes before linearized reading' (duration: 340.839815ms)","trace[1380262593] 'range keys from in-memory index tree' (duration: 19.720827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:22.599084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.243238Z","time spent":"355.757036ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:05:22.599119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.238252Z","time spent":"360.857988ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-22T22:05:22.598541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.711161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-22T22:05:22.599919Z","caller":"traceutil/trace.go:171","msg":"trace[265913887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1864; }","duration":"359.056475ms","start":"2026-07-22T22:05:22.240783Z","end":"2026-07-22T22:05:22.599839Z","steps":["trace[265913887] 'agreement among raft nodes before linearized reading' (duration: 338.37604ms)","trace[265913887] 'range keys from in-memory index tree' (duration: 19.212799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:22.600031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.240777Z","time spent":"359.215518ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T22:05:23.260889Z","caller":"traceutil/trace.go:171","msg":"trace[721095577] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"500.115478ms","start":"2026-07-22T22:05:22.760744Z","end":"2026-07-22T22:05:23.26086Z","steps":["trace[721095577] 'process raft request' (duration: 499.946255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:05:23.261244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.760704Z","time spent":"500.353861ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:05:23.304907Z","caller":"traceutil/trace.go:171","msg":"trace[711502168] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1941; }","duration":"332.952116ms","start":"2026-07-22T22:05:22.971928Z","end":"2026-07-22T22:05:23.304881Z","steps":["trace[711502168] 'read index received' (duration: 288.886991ms)","trace[711502168] 'applied index is now lower than readState.Index' (duration: 44.063965ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:05:23.304968Z","caller":"traceutil/trace.go:171","msg":"trace[404845827] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"541.153318ms","start":"2026-07-22T22:05:22.763784Z","end":"2026-07-22T22:05:23.304938Z","steps":["trace[404845827] 'process raft request' (duration: 540.958594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:05:23.305118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.763766Z","time spent":"541.264089ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3730,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:05:23.305142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.20371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-22T22:05:23.305505Z","caller":"traceutil/trace.go:171","msg":"trace[378253980] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"333.628188ms","start":"2026-07-22T22:05:22.971854Z","end":"2026-07-22T22:05:23.305482Z","steps":["trace[378253980] 'agreement among raft nodes before linearized reading' (duration: 333.124538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:05:23.305562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:05:22.971828Z","time spent":"333.7172ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-22T22:05:37.506965Z","caller":"traceutil/trace.go:171","msg":"trace[549697277] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"105.743149ms","start":"2026-07-22T22:05:37.401197Z","end":"2026-07-22T22:05:37.50694Z","steps":["trace[549697277] 'read index received' (duration: 105.580356ms)","trace[549697277] 'applied index is now lower than readState.Index' (duration: 161.963µs)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:05:37.507215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.995734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-22T22:05:37.507257Z","caller":"traceutil/trace.go:171","msg":"trace[326733131] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"106.091135ms","start":"2026-07-22T22:05:37.401154Z","end":"2026-07-22T22:05:37.507245Z","steps":["trace[326733131] 'agreement among raft nodes before linearized reading' (duration: 105.891691ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:05:37.507599Z","caller":"traceutil/trace.go:171","msg":"trace[1596483347] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"177.130288ms","start":"2026-07-22T22:05:37.330451Z","end":"2026-07-22T22:05:37.507581Z","steps":["trace[1596483347] 'process raft request' (duration: 176.338044ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:00.973143Z","caller":"traceutil/trace.go:171","msg":"trace[1556418656] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"130.414559ms","start":"2026-07-22T22:06:00.842705Z","end":"2026-07-22T22:06:00.97312Z","steps":["trace[1556418656] 'read index received' (duration: 130.358988ms)","trace[1556418656] 'applied index is now lower than readState.Index' (duration: 54.611µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:06:00.97322Z","caller":"traceutil/trace.go:171","msg":"trace[1622983122] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"140.484996ms","start":"2026-07-22T22:06:00.83269Z","end":"2026-07-22T22:06:00.973175Z","steps":["trace[1622983122] 'process raft request' (duration: 140.287503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:00.973372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.644202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-22T22:06:00.973422Z","caller":"traceutil/trace.go:171","msg":"trace[1638471175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2121; }","duration":"130.758683ms","start":"2026-07-22T22:06:00.84265Z","end":"2026-07-22T22:06:00.973409Z","steps":["trace[1638471175] 'agreement among raft nodes before linearized reading' (duration: 130.591481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:01.00008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.191142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-22T22:06:01.000093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.372948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-22T22:06:01.000135Z","caller":"traceutil/trace.go:171","msg":"trace[768845610] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2122; }","duration":"106.278933ms","start":"2026-07-22T22:06:00.89384Z","end":"2026-07-22T22:06:01.000119Z","steps":["trace[768845610] 'agreement among raft nodes before linearized reading' (duration: 106.062739ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:01.000142Z","caller":"traceutil/trace.go:171","msg":"trace[1185289509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2122; }","duration":"112.43396ms","start":"2026-07-22T22:06:00.887695Z","end":"2026-07-22T22:06:01.000129Z","steps":["trace[1185289509] 'agreement among raft nodes before linearized reading' (duration: 112.318647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:01.000192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.248686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-22T22:06:01.000316Z","caller":"traceutil/trace.go:171","msg":"trace[1774963476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2122; }","duration":"110.414338ms","start":"2026-07-22T22:06:00.889863Z","end":"2026-07-22T22:06:01.000277Z","steps":["trace[1774963476] 'agreement among raft nodes before linearized reading' (duration: 110.066242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:01.001154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.386755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-22T22:06:01.001214Z","caller":"traceutil/trace.go:171","msg":"trace[557408340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2122; }","duration":"115.477397ms","start":"2026-07-22T22:06:00.885723Z","end":"2026-07-22T22:06:01.001201Z","steps":["trace[557408340] 'agreement among raft nodes before linearized reading' (duration: 114.172846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:01.336989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.824484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:06:01.337082Z","caller":"traceutil/trace.go:171","msg":"trace[1613099256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2127; }","duration":"258.954766ms","start":"2026-07-22T22:06:01.078106Z","end":"2026-07-22T22:06:01.337061Z","steps":["trace[1613099256] 'range keys from in-memory index tree' (duration: 258.733163ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:01.725785Z","caller":"traceutil/trace.go:171","msg":"trace[153881138] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"106.189737ms","start":"2026-07-22T22:06:01.619553Z","end":"2026-07-22T22:06:01.725742Z","steps":["trace[153881138] 'process raft request' (duration: 105.964524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:02.453003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.382212ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:06:02.453167Z","caller":"traceutil/trace.go:171","msg":"trace[1196282143] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2128; }","duration":"307.563384ms","start":"2026-07-22T22:06:02.145583Z","end":"2026-07-22T22:06:02.453147Z","steps":["trace[1196282143] 'range keys from in-memory index tree' (duration: 307.364492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:02.454021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.095095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:06:02.454157Z","caller":"traceutil/trace.go:171","msg":"trace[1330541913] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"640.786494ms","start":"2026-07-22T22:06:01.813355Z","end":"2026-07-22T22:06:02.454142Z","steps":["trace[1330541913] 'process raft request' (duration: 490.998398ms)","trace[1330541913] 'compare' (duration: 149.007664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:06:02.454237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:01.813326Z","time spent":"640.882677ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:06:02.716229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.698497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:06:02.716592Z","caller":"traceutil/trace.go:171","msg":"trace[815330261] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"770.609925ms","start":"2026-07-22T22:06:01.945941Z","end":"2026-07-22T22:06:02.716551Z","steps":["trace[815330261] 'process raft request' (duration: 649.482733ms)","trace[815330261] 'compare' (duration: 120.476822ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:06:02.716742Z","caller":"traceutil/trace.go:171","msg":"trace[768222936] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"376.744436ms","start":"2026-07-22T22:06:02.339974Z","end":"2026-07-22T22:06:02.716718Z","steps":["trace[768222936] 'read index received' (duration: 255.567401ms)","trace[768222936] 'applied index is now lower than readState.Index' (duration: 121.174294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:06:02.716771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:01.945916Z","time spent":"770.739158ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:06:02.717055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.0652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-07-22T22:06:02.717145Z","caller":"traceutil/trace.go:171","msg":"trace[327540378] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2130; }","duration":"377.208712ms","start":"2026-07-22T22:06:02.339914Z","end":"2026-07-22T22:06:02.717123Z","steps":["trace[327540378] 'agreement among raft nodes before linearized reading' (duration: 376.927228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:02.717203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:06:02.339866Z","time spent":"377.323613ms","remote":"127.0.0.1:50852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-07-22T22:06:02.71731Z","caller":"traceutil/trace.go:171","msg":"trace[802689962] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"292.590624ms","start":"2026-07-22T22:06:02.424699Z","end":"2026-07-22T22:06:02.717289Z","steps":["trace[802689962] 'process raft request' (duration: 292.290919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:02.717407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.272319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:06:02.717501Z","caller":"traceutil/trace.go:171","msg":"trace[194911462] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2131; }","duration":"208.397472ms","start":"2026-07-22T22:06:02.509083Z","end":"2026-07-22T22:06:02.717481Z","steps":["trace[194911462] 'agreement among raft nodes before linearized reading' (duration: 208.265709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:02.71786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.137018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:06:02.71797Z","caller":"traceutil/trace.go:171","msg":"trace[592772113] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2131; }","duration":"285.267739ms","start":"2026-07-22T22:06:02.432637Z","end":"2026-07-22T22:06:02.717904Z","steps":["trace[592772113] 'agreement among raft nodes before linearized reading' (duration: 284.584029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:06:02.71766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.166251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:06:02.718211Z","caller":"traceutil/trace.go:171","msg":"trace[783230154] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2131; }","duration":"170.873181ms","start":"2026-07-22T22:06:02.547321Z","end":"2026-07-22T22:06:02.718194Z","steps":["trace[783230154] 'agreement among raft nodes before linearized reading' (duration: 169.969157ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:06:44.529626Z","caller":"traceutil/trace.go:171","msg":"trace[299538463] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"133.759202ms","start":"2026-07-22T22:06:44.395831Z","end":"2026-07-22T22:06:44.52959Z","steps":["trace[299538463] 'process raft request' (duration: 133.495437ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:07:26.435838Z","caller":"traceutil/trace.go:171","msg":"trace[338659631] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"256.497507ms","start":"2026-07-22T22:07:26.179312Z","end":"2026-07-22T22:07:26.43581Z","steps":["trace[338659631] 'process raft request' (duration: 256.091011ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:02.796467Z","caller":"traceutil/trace.go:171","msg":"trace[974630998] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"124.7967ms","start":"2026-07-22T22:11:02.671649Z","end":"2026-07-22T22:11:02.796445Z","steps":["trace[974630998] 'process raft request' (duration: 54.702013ms)","trace[974630998] 'compare' (duration: 69.936794ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:03.198091Z","caller":"traceutil/trace.go:171","msg":"trace[818452764] linearizableReadLoop","detail":"{readStateIndex:4710; appliedIndex:4709; }","duration":"175.68704ms","start":"2026-07-22T22:11:03.02238Z","end":"2026-07-22T22:11:03.198067Z","steps":["trace[818452764] 'read index received' (duration: 175.311223ms)","trace[818452764] 'applied index is now lower than readState.Index' (duration: 374.626µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:03.198213Z","caller":"traceutil/trace.go:171","msg":"trace[1853460135] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"201.517043ms","start":"2026-07-22T22:11:02.996663Z","end":"2026-07-22T22:11:03.19818Z","steps":["trace[1853460135] 'process raft request' (duration: 201.088926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:03.198336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.938504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-22T22:11:03.198387Z","caller":"traceutil/trace.go:171","msg":"trace[604233263] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4539; }","duration":"176.044977ms","start":"2026-07-22T22:11:03.022325Z","end":"2026-07-22T22:11:03.19837Z","steps":["trace[604233263] 'agreement among raft nodes before linearized reading' (duration: 175.840082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:03.322471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.773032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-22T22:11:03.322584Z","caller":"traceutil/trace.go:171","msg":"trace[699010655] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4539; }","duration":"109.928494ms","start":"2026-07-22T22:11:03.212627Z","end":"2026-07-22T22:11:03.322555Z","steps":["trace[699010655] 'range keys from in-memory index tree' (duration: 109.587668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.04674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312882366821556,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-22T22:11:09.254335Z","caller":"traceutil/trace.go:171","msg":"trace[1120070771] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"714.25022ms","start":"2026-07-22T22:11:08.540052Z","end":"2026-07-22T22:11:09.254302Z","steps":["trace[1120070771] 'process raft request' (duration: 714.049606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.254648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.540035Z","time spent":"714.391084ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:09.293706Z","caller":"traceutil/trace.go:171","msg":"trace[927357087] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"751.034398ms","start":"2026-07-22T22:11:08.542634Z","end":"2026-07-22T22:11:09.293669Z","steps":["trace[927357087] 'process raft request' (duration: 735.658199ms)","trace[927357087] 'compare' (duration: 14.834359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:09.293826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.542619Z","time spent":"751.140661ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:09.294062Z","caller":"traceutil/trace.go:171","msg":"trace[1172041233] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"668.885395ms","start":"2026-07-22T22:11:08.625164Z","end":"2026-07-22T22:11:09.294049Z","steps":["trace[1172041233] 'process raft request' (duration: 668.144931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.294117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.625136Z","time spent":"668.952236ms","remote":"127.0.0.1:56506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2724,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-22T22:11:09.294227Z","caller":"traceutil/trace.go:171","msg":"trace[1645789846] linearizableReadLoop","detail":"{readStateIndex:4762; appliedIndex:4759; }","duration":"753.640146ms","start":"2026-07-22T22:11:08.540578Z","end":"2026-07-22T22:11:09.294218Z","steps":["trace[1645789846] 'read index received' (duration: 713.61326ms)","trace[1645789846] 'applied index is now lower than readState.Index' (duration: 40.025706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:09.294511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.762662ms","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-07-22T22:11:09.294538Z","caller":"traceutil/trace.go:171","msg":"trace[1098029755] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4591; }","duration":"751.804542ms","start":"2026-07-22T22:11:08.542726Z","end":"2026-07-22T22:11:09.29453Z","steps":["trace[1098029755] 'agreement among raft nodes before linearized reading' (duration: 751.761532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.294561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.542721Z","time spent":"751.834553ms","remote":"127.0.0.1:51092","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-07-22T22:11:09.294737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.391205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-22T22:11:09.294731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.119475ms","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-07-22T22:11:09.29476Z","caller":"traceutil/trace.go:171","msg":"trace[241841071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4591; }","duration":"718.425766ms","start":"2026-07-22T22:11:08.576328Z","end":"2026-07-22T22:11:09.294754Z","steps":["trace[241841071] 'agreement among raft nodes before linearized reading' (duration: 718.335204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.294785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.576322Z","time spent":"718.457267ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-22T22:11:09.294786Z","caller":"traceutil/trace.go:171","msg":"trace[25763339] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4591; }","duration":"709.184988ms","start":"2026-07-22T22:11:08.585586Z","end":"2026-07-22T22:11:09.294772Z","steps":["trace[25763339] 'agreement among raft nodes before linearized reading' (duration: 709.016184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.294823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.585576Z","time spent":"709.234588ms","remote":"127.0.0.1:50920","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-07-22T22:11:09.294945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.366649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12971"} {"level":"info","ts":"2026-07-22T22:11:09.294965Z","caller":"traceutil/trace.go:171","msg":"trace[1697524184] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4591; }","duration":"754.40817ms","start":"2026-07-22T22:11:08.54055Z","end":"2026-07-22T22:11:09.294958Z","steps":["trace[1697524184] 'agreement among raft nodes before linearized reading' (duration: 754.344379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.294985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.540542Z","time spent":"754.43589ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12994,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-22T22:11:09.295012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.43755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-22T22:11:09.295042Z","caller":"traceutil/trace.go:171","msg":"trace[1388965066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4591; }","duration":"721.483602ms","start":"2026-07-22T22:11:08.57355Z","end":"2026-07-22T22:11:09.295034Z","steps":["trace[1388965066] 'agreement among raft nodes before linearized reading' (duration: 721.39456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.295075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.573543Z","time spent":"721.525493ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-22T22:11:09.295134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.2431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:09.295173Z","caller":"traceutil/trace.go:171","msg":"trace[1049188537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4591; }","duration":"219.314041ms","start":"2026-07-22T22:11:09.075848Z","end":"2026-07-22T22:11:09.295162Z","steps":["trace[1049188537] 'agreement among raft nodes before linearized reading' (duration: 219.24441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.295435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.325826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-22T22:11:09.295543Z","caller":"traceutil/trace.go:171","msg":"trace[1956110379] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4591; }","duration":"733.472279ms","start":"2026-07-22T22:11:08.562039Z","end":"2026-07-22T22:11:09.295511Z","steps":["trace[1956110379] 'agreement among raft nodes before linearized reading' (duration: 733.145652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.295617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:08.562027Z","time spent":"733.57159ms","remote":"127.0.0.1:50812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-07-22T22:11:09.567968Z","caller":"traceutil/trace.go:171","msg":"trace[845828571] linearizableReadLoop","detail":"{readStateIndex:4779; appliedIndex:4777; }","duration":"102.98782ms","start":"2026-07-22T22:11:09.464957Z","end":"2026-07-22T22:11:09.567945Z","steps":["trace[845828571] 'read index received' (duration: 57.188758ms)","trace[845828571] 'applied index is now lower than readState.Index' (duration: 45.798042ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:09.568263Z","caller":"traceutil/trace.go:171","msg":"trace[2018586989] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"129.336789ms","start":"2026-07-22T22:11:09.438904Z","end":"2026-07-22T22:11:09.568241Z","steps":["trace[2018586989] 'process raft request' (duration: 83.452666ms)","trace[2018586989] 'compare' (duration: 45.15906ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:09.568553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.5385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-22T22:11:09.568642Z","caller":"traceutil/trace.go:171","msg":"trace[823731433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4606; }","duration":"103.699842ms","start":"2026-07-22T22:11:09.46492Z","end":"2026-07-22T22:11:09.56862Z","steps":["trace[823731433] 'agreement among raft nodes before linearized reading' (duration: 103.410327ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.713699Z","caller":"traceutil/trace.go:171","msg":"trace[1246276103] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"142.527188ms","start":"2026-07-22T22:11:09.57111Z","end":"2026-07-22T22:11:09.713637Z","steps":["trace[1246276103] 'process raft request' (duration: 104.248543ms)","trace[1246276103] 'compare' (duration: 38.042761ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:09.724746Z","caller":"traceutil/trace.go:171","msg":"trace[1797142599] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"153.487817ms","start":"2026-07-22T22:11:09.571227Z","end":"2026-07-22T22:11:09.724715Z","steps":["trace[1797142599] 'process raft request' (duration: 153.269913ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.724837Z","caller":"traceutil/trace.go:171","msg":"trace[1513656866] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"151.864287ms","start":"2026-07-22T22:11:09.572937Z","end":"2026-07-22T22:11:09.724802Z","steps":["trace[1513656866] 'process raft request' (duration: 151.723844ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.725009Z","caller":"traceutil/trace.go:171","msg":"trace[822764900] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"151.609502ms","start":"2026-07-22T22:11:09.573378Z","end":"2026-07-22T22:11:09.724988Z","steps":["trace[822764900] 'process raft request' (duration: 151.330727ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.725135Z","caller":"traceutil/trace.go:171","msg":"trace[513683559] linearizableReadLoop","detail":"{readStateIndex:4782; appliedIndex:4779; }","duration":"151.778076ms","start":"2026-07-22T22:11:09.573281Z","end":"2026-07-22T22:11:09.72506Z","steps":["trace[513683559] 'read index received' (duration: 102.011413ms)","trace[513683559] 'applied index is now lower than readState.Index' (duration: 49.765213ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:09.725281Z","caller":"traceutil/trace.go:171","msg":"trace[415511868] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"150.502403ms","start":"2026-07-22T22:11:09.574766Z","end":"2026-07-22T22:11:09.725268Z","steps":["trace[415511868] 'process raft request' (duration: 150.057545ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.725321Z","caller":"traceutil/trace.go:171","msg":"trace[535378351] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"150.89637ms","start":"2026-07-22T22:11:09.574412Z","end":"2026-07-22T22:11:09.725308Z","steps":["trace[535378351] 'process raft request' (duration: 150.33573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.725406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.108393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-51b0ddb9-773b-4e9c-a958-a544f36157c8\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-07-22T22:11:09.725453Z","caller":"traceutil/trace.go:171","msg":"trace[310062162] range","detail":"{range_begin:/registry/persistentvolumes/pvc-51b0ddb9-773b-4e9c-a958-a544f36157c8; range_end:; response_count:1; response_revision:4614; }","duration":"152.181864ms","start":"2026-07-22T22:11:09.57326Z","end":"2026-07-22T22:11:09.725442Z","steps":["trace[310062162] 'agreement among raft nodes before linearized reading' (duration: 152.047831ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.725595Z","caller":"traceutil/trace.go:171","msg":"trace[665972380] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"150.909141ms","start":"2026-07-22T22:11:09.57465Z","end":"2026-07-22T22:11:09.72556Z","steps":["trace[665972380] 'process raft request' (duration: 150.135007ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.725655Z","caller":"traceutil/trace.go:171","msg":"trace[732446033] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"144.471123ms","start":"2026-07-22T22:11:09.581174Z","end":"2026-07-22T22:11:09.725645Z","steps":["trace[732446033] 'process raft request' (duration: 143.819901ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.951891Z","caller":"traceutil/trace.go:171","msg":"trace[788186362] linearizableReadLoop","detail":"{readStateIndex:4799; appliedIndex:4797; }","duration":"153.151221ms","start":"2026-07-22T22:11:09.798721Z","end":"2026-07-22T22:11:09.951872Z","steps":["trace[788186362] 'read index received' (duration: 29.973515ms)","trace[788186362] 'applied index is now lower than readState.Index' (duration: 123.177056ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:09.951967Z","caller":"traceutil/trace.go:171","msg":"trace[637796852] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"152.304065ms","start":"2026-07-22T22:11:09.799621Z","end":"2026-07-22T22:11:09.951925Z","steps":["trace[637796852] 'process raft request' (duration: 152.213454ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.952116Z","caller":"traceutil/trace.go:171","msg":"trace[544662674] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"155.299679ms","start":"2026-07-22T22:11:09.796788Z","end":"2026-07-22T22:11:09.952087Z","steps":["trace[544662674] 'process raft request' (duration: 154.957293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:09.95228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.060134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-22T22:11:09.952395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.62476ms","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-07-22T22:11:09.952453Z","caller":"traceutil/trace.go:171","msg":"trace[1975299520] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4627; }","duration":"153.765182ms","start":"2026-07-22T22:11:09.798678Z","end":"2026-07-22T22:11:09.952443Z","steps":["trace[1975299520] 'agreement among raft nodes before linearized reading' (duration: 153.308633ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:09.952501Z","caller":"traceutil/trace.go:171","msg":"trace[1760236454] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4627; }","duration":"118.312588ms","start":"2026-07-22T22:11:09.834125Z","end":"2026-07-22T22:11:09.952438Z","steps":["trace[1760236454] 'agreement among raft nodes before linearized reading' (duration: 118.053574ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:12.693593Z","caller":"traceutil/trace.go:171","msg":"trace[869784916] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"213.54752ms","start":"2026-07-22T22:11:12.480022Z","end":"2026-07-22T22:11:12.693569Z","steps":["trace[869784916] 'read index received' (duration: 213.464449ms)","trace[869784916] 'applied index is now lower than readState.Index' (duration: 82.171µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:12.693782Z","caller":"traceutil/trace.go:171","msg":"trace[1974159567] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"221.611076ms","start":"2026-07-22T22:11:12.47215Z","end":"2026-07-22T22:11:12.693762Z","steps":["trace[1974159567] 'process raft request' (duration: 221.26889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:12.693809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.764965ms","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-07-22T22:11:12.694003Z","caller":"traceutil/trace.go:171","msg":"trace[713607955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4690; }","duration":"214.013818ms","start":"2026-07-22T22:11:12.479977Z","end":"2026-07-22T22:11:12.693991Z","steps":["trace[713607955] 'agreement among raft nodes before linearized reading' (duration: 213.684882ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:19.885227Z","caller":"traceutil/trace.go:171","msg":"trace[227066455] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"107.28714ms","start":"2026-07-22T22:11:19.777916Z","end":"2026-07-22T22:11:19.885203Z","steps":["trace[227066455] 'process raft request' (duration: 106.928043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:23.039062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.652051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-22T22:11:23.039183Z","caller":"traceutil/trace.go:171","msg":"trace[857441901] linearizableReadLoop","detail":"{readStateIndex:5001; appliedIndex:5000; }","duration":"292.880297ms","start":"2026-07-22T22:11:22.746287Z","end":"2026-07-22T22:11:23.039167Z","steps":["trace[857441901] 'read index received' (duration: 118.093624ms)","trace[857441901] 'applied index is now lower than readState.Index' (duration: 174.785483ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:23.039272Z","caller":"traceutil/trace.go:171","msg":"trace[867176556] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"371.55809ms","start":"2026-07-22T22:11:22.667671Z","end":"2026-07-22T22:11:23.039229Z","steps":["trace[867176556] 'process raft request' (duration: 196.654284ms)","trace[867176556] 'compare' (duration: 174.520048ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:23.039426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-22T22:11:22.667658Z","time spent":"371.688391ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10665,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-22T22:11:23.039449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.165062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-22T22:11:23.039444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.734902ms","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-07-22T22:11:23.039497Z","caller":"traceutil/trace.go:171","msg":"trace[1712183714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4822; }","duration":"293.246964ms","start":"2026-07-22T22:11:22.746236Z","end":"2026-07-22T22:11:23.039483Z","steps":["trace[1712183714] 'agreement among raft nodes before linearized reading' (duration: 293.01897ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:23.039519Z","caller":"traceutil/trace.go:171","msg":"trace[1148181172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4822; }","duration":"289.859284ms","start":"2026-07-22T22:11:22.749641Z","end":"2026-07-22T22:11:23.0395Z","steps":["trace[1148181172] 'agreement among raft nodes before linearized reading' (duration: 289.63528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:23.039571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.037958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-22T22:11:23.039685Z","caller":"traceutil/trace.go:171","msg":"trace[731551995] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4822; }","duration":"280.197002ms","start":"2026-07-22T22:11:22.759459Z","end":"2026-07-22T22:11:23.039656Z","steps":["trace[731551995] 'agreement among raft nodes before linearized reading' (duration: 279.825705ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:23.626546Z","caller":"traceutil/trace.go:171","msg":"trace[1211758627] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"129.219569ms","start":"2026-07-22T22:11:23.497289Z","end":"2026-07-22T22:11:23.626508Z","steps":["trace[1211758627] 'process raft request' (duration: 85.233096ms)","trace[1211758627] 'compare' (duration: 43.649106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:23.627064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.605681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-07-22T22:11:23.627183Z","caller":"traceutil/trace.go:171","msg":"trace[768222101] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4832; }","duration":"100.797914ms","start":"2026-07-22T22:11:23.526359Z","end":"2026-07-22T22:11:23.627157Z","steps":["trace[768222101] 'agreement among raft nodes before linearized reading' (duration: 99.967299ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:24.276684Z","caller":"traceutil/trace.go:171","msg":"trace[1885099965] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"296.613092ms","start":"2026-07-22T22:11:23.980047Z","end":"2026-07-22T22:11:24.27666Z","steps":["trace[1885099965] 'process raft request' (duration: 224.586622ms)","trace[1885099965] 'compare' (duration: 71.895296ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:24.276778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.963643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:11:24.276844Z","caller":"traceutil/trace.go:171","msg":"trace[1346598852] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:4835; }","duration":"191.076155ms","start":"2026-07-22T22:11:24.085756Z","end":"2026-07-22T22:11:24.276832Z","steps":["trace[1346598852] 'agreement among raft nodes before linearized reading' (duration: 190.964583ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:24.276672Z","caller":"traceutil/trace.go:171","msg":"trace[1439160285] linearizableReadLoop","detail":"{readStateIndex:5014; appliedIndex:5013; }","duration":"190.853901ms","start":"2026-07-22T22:11:24.085794Z","end":"2026-07-22T22:11:24.276648Z","steps":["trace[1439160285] 'read index received' (duration: 118.855852ms)","trace[1439160285] 'applied index is now lower than readState.Index' (duration: 71.997119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-22T22:11:24.28663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.627695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-22T22:11:24.286704Z","caller":"traceutil/trace.go:171","msg":"trace[1326902988] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:4836; }","duration":"164.725277ms","start":"2026-07-22T22:11:24.121952Z","end":"2026-07-22T22:11:24.286678Z","steps":["trace[1326902988] 'agreement among raft nodes before linearized reading' (duration: 164.302009ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:24.287032Z","caller":"traceutil/trace.go:171","msg":"trace[2002666462] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"184.429827ms","start":"2026-07-22T22:11:24.102593Z","end":"2026-07-22T22:11:24.287023Z","steps":["trace[2002666462] 'process raft request' (duration: 183.565112ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:11:58.005552Z","caller":"traceutil/trace.go:171","msg":"trace[1866616533] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5324; }","duration":"101.703246ms","start":"2026-07-22T22:11:57.903821Z","end":"2026-07-22T22:11:58.005524Z","steps":["trace[1866616533] 'read index received' (duration: 101.156836ms)","trace[1866616533] 'applied index is now lower than readState.Index' (duration: 545.38µs)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:11:58.005627Z","caller":"traceutil/trace.go:171","msg":"trace[14990785] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"105.553661ms","start":"2026-07-22T22:11:57.900038Z","end":"2026-07-22T22:11:58.005591Z","steps":["trace[14990785] 'process raft request' (duration: 104.9331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:11:58.005754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.899949ms","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-07-22T22:11:58.005796Z","caller":"traceutil/trace.go:171","msg":"trace[1840870374] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5138; }","duration":"101.99068ms","start":"2026-07-22T22:11:57.903792Z","end":"2026-07-22T22:11:58.005783Z","steps":["trace[1840870374] 'agreement among raft nodes before linearized reading' (duration: 101.836217ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:02.14002Z","caller":"traceutil/trace.go:171","msg":"trace[2086581576] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"111.547873ms","start":"2026-07-22T22:12:02.028446Z","end":"2026-07-22T22:12:02.139994Z","steps":["trace[2086581576] 'process raft request' (duration: 111.36876ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:42.851357Z","caller":"traceutil/trace.go:171","msg":"trace[657752204] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"176.198711ms","start":"2026-07-22T22:12:42.675134Z","end":"2026-07-22T22:12:42.851332Z","steps":["trace[657752204] 'process raft request' (duration: 176.025519ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:12:50.124014Z","caller":"traceutil/trace.go:171","msg":"trace[603992131] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"122.146784ms","start":"2026-07-22T22:12:50.001841Z","end":"2026-07-22T22:12:50.123988Z","steps":["trace[603992131] 'process raft request' (duration: 121.950561ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:13:05.741853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-07-22T22:13:05.838464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"95.039228ms","hash":1752536036,"current-db-size-bytes":54284288,"current-db-size":"54 MB","current-db-size-in-use-bytes":29425664,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-22T22:13:05.838553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1752536036,"revision":2903,"compact-revision":-1} {"level":"info","ts":"2026-07-22T22:13:25.807081Z","caller":"traceutil/trace.go:171","msg":"trace[130731621] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"182.485065ms","start":"2026-07-22T22:13:25.62456Z","end":"2026-07-22T22:13:25.807045Z","steps":["trace[130731621] 'process raft request' (duration: 182.268851ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:13:25.902769Z","caller":"traceutil/trace.go:171","msg":"trace[850375] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"276.967924ms","start":"2026-07-22T22:13:25.625778Z","end":"2026-07-22T22:13:25.902746Z","steps":["trace[850375] 'process raft request' (duration: 274.937521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-22T22:13:25.902884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.587062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-22T22:13:25.902768Z","caller":"traceutil/trace.go:171","msg":"trace[208727537] linearizableReadLoop","detail":"{readStateIndex:5928; appliedIndex:5926; }","duration":"108.43502ms","start":"2026-07-22T22:13:25.794273Z","end":"2026-07-22T22:13:25.902708Z","steps":["trace[208727537] 'read index received' (duration: 12.58214ms)","trace[208727537] 'applied index is now lower than readState.Index' (duration: 95.8514ms)"],"step_count":2} {"level":"info","ts":"2026-07-22T22:13:25.902925Z","caller":"traceutil/trace.go:171","msg":"trace[1737913721] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5721; }","duration":"108.682205ms","start":"2026-07-22T22:13:25.794231Z","end":"2026-07-22T22:13:25.902913Z","steps":["trace[1737913721] 'agreement among raft nodes before linearized reading' (duration: 108.586393ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:15:02.308161Z","caller":"traceutil/trace.go:171","msg":"trace[118523773] transaction","detail":"{read_only:false; response_revision:6415; number_of_response:1; }","duration":"146.597702ms","start":"2026-07-22T22:15:02.161539Z","end":"2026-07-22T22:15:02.308137Z","steps":["trace[118523773] 'process raft request' (duration: 146.045303ms)"],"step_count":1} {"level":"info","ts":"2026-07-22T22:18:05.747336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5621} {"level":"info","ts":"2026-07-22T22:18:05.831444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5621,"took":"82.901147ms","hash":630892657,"current-db-size-bytes":54284288,"current-db-size":"54 MB","current-db-size-in-use-bytes":18378752,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-22T22:18:05.831557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":630892657,"revision":5621,"compact-revision":2903}