{"level":"warn","ts":"2026-04-20T02:06:25.301258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T02:06:25.30134Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T02:06:25.301403Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T02:06:25.301411Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-20T02:06:25.301426Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T02:06:25.302009Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-04-20T02:06:25.302084Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T02:06:25.309159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.759531ms"} {"level":"info","ts":"2026-04-20T02:06:25.314232Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-04-20T02:06:25.314346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T02:06:25.314372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-04-20T02:06:25.314386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T02:06:25.314395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-04-20T02:06:25.314441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-04-20T02:06:25.323822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T02:06:25.32561Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T02:06:25.326964Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T02:06:25.329113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T02:06:25.329279Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T02:06:25.329398Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T02:06:25.329557Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T02:06:25.329595Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T02:06:25.33053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-04-20T02:06:25.331105Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-04-20T02:06:25.334727Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T02:06:25.334836Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-20T02:06:25.334897Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-04-20T02:06:25.335064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T02:06:25.335135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T02:06:25.616047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T02:06:25.616109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T02:06:25.616139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-04-20T02:06:25.616162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-04-20T02:06:25.616174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-20T02:06:25.616184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-04-20T02:06:25.616197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-04-20T02:06:25.617399Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T02:06:25.617413Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:25.617455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T02:06:25.6175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T02:06:25.617653Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T02:06:25.617698Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T02:06:25.618532Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:25.618674Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:25.618717Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:25.620548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T02:06:25.622045Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-04-20T02:07:01.76397Z","caller":"traceutil/trace.go:171","msg":"trace[100651030] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"136.182911ms","start":"2026-04-20T02:07:01.627735Z","end":"2026-04-20T02:07:01.763917Z","steps":["trace[100651030] 'read index received' (duration: 134.877656ms)","trace[100651030] 'applied index is now lower than readState.Index' (duration: 1.304255ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:01.764029Z","caller":"traceutil/trace.go:171","msg":"trace[430540212] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"169.119768ms","start":"2026-04-20T02:07:01.594869Z","end":"2026-04-20T02:07:01.763988Z","steps":["trace[430540212] 'process raft request' (duration: 168.078888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:01.764349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.584788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:07:01.764487Z","caller":"traceutil/trace.go:171","msg":"trace[28106641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"136.790752ms","start":"2026-04-20T02:07:01.627672Z","end":"2026-04-20T02:07:01.764463Z","steps":["trace[28106641] 'agreement among raft nodes before linearized reading' (duration: 136.432295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:01.918222Z","caller":"traceutil/trace.go:171","msg":"trace[159672535] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"147.71239ms","start":"2026-04-20T02:07:01.770479Z","end":"2026-04-20T02:07:01.918192Z","steps":["trace[159672535] 'process raft request' (duration: 143.236365ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:01.964867Z","caller":"traceutil/trace.go:171","msg":"trace[2073804715] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"181.940251ms","start":"2026-04-20T02:07:01.782885Z","end":"2026-04-20T02:07:01.964826Z","steps":["trace[2073804715] 'process raft request' (duration: 181.490983ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:01.965138Z","caller":"traceutil/trace.go:171","msg":"trace[604800683] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"143.811875ms","start":"2026-04-20T02:07:01.821297Z","end":"2026-04-20T02:07:01.965109Z","steps":["trace[604800683] 'process raft request' (duration: 143.335866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:02.406978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.692105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:02.407079Z","caller":"traceutil/trace.go:171","msg":"trace[1023355240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"178.833877ms","start":"2026-04-20T02:07:02.228218Z","end":"2026-04-20T02:07:02.407052Z","steps":["trace[1023355240] 'range keys from in-memory index tree' (duration: 178.545341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:18.046203Z","caller":"traceutil/trace.go:171","msg":"trace[515017823] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"180.375597ms","start":"2026-04-20T02:07:17.865805Z","end":"2026-04-20T02:07:18.04618Z","steps":["trace[515017823] 'process raft request' (duration: 180.247254ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:18.148156Z","caller":"traceutil/trace.go:171","msg":"trace[2026427250] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"105.58239ms","start":"2026-04-20T02:07:18.042533Z","end":"2026-04-20T02:07:18.148115Z","steps":["trace[2026427250] 'process raft request' (duration: 81.259237ms)","trace[2026427250] 'compare' (duration: 24.044678ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:46.421413Z","caller":"traceutil/trace.go:171","msg":"trace[2076712980] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"113.778878ms","start":"2026-04-20T02:07:46.307603Z","end":"2026-04-20T02:07:46.421382Z","steps":["trace[2076712980] 'process raft request' (duration: 113.589835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.165426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215107,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T02:08:25.615018Z","caller":"traceutil/trace.go:171","msg":"trace[772375914] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"978.155858ms","start":"2026-04-20T02:08:24.636825Z","end":"2026-04-20T02:08:25.614981Z","steps":["trace[772375914] 'process raft request' (duration: 977.984685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.61559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.636807Z","time spent":"978.284981ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:25.866513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.487068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:25.866681Z","caller":"traceutil/trace.go:171","msg":"trace[64128673] linearizableReadLoop","detail":"{readStateIndex:1780; appliedIndex:1778; }","duration":"1.201578911s","start":"2026-04-20T02:08:24.665079Z","end":"2026-04-20T02:08:25.866658Z","steps":["trace[64128673] 'read index received' (duration: 949.781934ms)","trace[64128673] 'applied index is now lower than readState.Index' (duration: 251.795617ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:25.86674Z","caller":"traceutil/trace.go:171","msg":"trace[1256593085] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"870.823175ms","start":"2026-04-20T02:08:24.995867Z","end":"2026-04-20T02:08:25.86669Z","steps":["trace[1256593085] 'process raft request' (duration: 747.835997ms)","trace[1256593085] 'compare' (duration: 122.230633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:25.866942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201827035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-04-20T02:08:25.866997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.995835Z","time spent":"871.023779ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:25.867037Z","caller":"traceutil/trace.go:171","msg":"trace[1202910574] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1725; }","duration":"1.201968397s","start":"2026-04-20T02:08:24.665048Z","end":"2026-04-20T02:08:25.867016Z","steps":["trace[1202910574] 'agreement among raft nodes before linearized reading' (duration: 1.201742263s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.867105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.665031Z","time spent":"1.20206516s","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T02:08:25.867117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111674366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:25.867232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.773946ms","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-04-20T02:08:25.867226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.724205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T02:08:25.867259Z","caller":"traceutil/trace.go:171","msg":"trace[694021856] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1725; }","duration":"1.111861409s","start":"2026-04-20T02:08:24.755358Z","end":"2026-04-20T02:08:25.867219Z","steps":["trace[694021856] 'agreement among raft nodes before linearized reading' (duration: 1.111606634s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.867293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.655097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T02:08:25.86732Z","caller":"traceutil/trace.go:171","msg":"trace[186753792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1725; }","duration":"991.878938ms","start":"2026-04-20T02:08:24.875419Z","end":"2026-04-20T02:08:25.867298Z","steps":["trace[186753792] 'agreement among raft nodes before linearized reading' (duration: 991.575802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.867328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.755332Z","time spent":"1.111983602s","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T02:08:25.867358Z","caller":"traceutil/trace.go:171","msg":"trace[1132827347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1725; }","duration":"439.760139ms","start":"2026-04-20T02:08:25.427584Z","end":"2026-04-20T02:08:25.867344Z","steps":["trace[1132827347] 'agreement among raft nodes before linearized reading' (duration: 439.532985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.867371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.875381Z","time spent":"991.98154ms","remote":"127.0.0.1:54556","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":"warn","ts":"2026-04-20T02:08:25.867412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:25.427561Z","time spent":"439.841691ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T02:08:25.867302Z","caller":"traceutil/trace.go:171","msg":"trace[212224566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1725; }","duration":"991.900698ms","start":"2026-04-20T02:08:24.875385Z","end":"2026-04-20T02:08:25.867286Z","steps":["trace[212224566] 'agreement among raft nodes before linearized reading' (duration: 991.647584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:25.867474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.875361Z","time spent":"992.100742ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T02:08:26.506873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:27.007993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:27.50888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:28.009749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:28.22782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998730487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T02:08:28.227908Z","caller":"traceutil/trace.go:171","msg":"trace[1272617111] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99884071s","start":"2026-04-20T02:08:26.229048Z","end":"2026-04-20T02:08:28.227888Z","steps":["trace[1272617111] 'agreement among raft nodes before linearized reading' (duration: 1.998727657s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:28.227988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:26.228929Z","time spent":"1.999046433s","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 02:08:28 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:28.509888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:29.010552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:29.511494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:29.727453Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.817624545s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:08:29.732765Z","caller":"traceutil/trace.go:171","msg":"trace[2078062190] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"3.860128216s","start":"2026-04-20T02:08:25.872592Z","end":"2026-04-20T02:08:29.73272Z","steps":["trace[2078062190] 'process raft request' (duration: 3.855272004s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.732971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:25.87258Z","time spent":"3.86030197s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:29.733551Z","caller":"traceutil/trace.go:171","msg":"trace[1910952573] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"3.859378813s","start":"2026-04-20T02:08:25.874131Z","end":"2026-04-20T02:08:29.73351Z","steps":["trace[1910952573] 'process raft request' (duration: 3.858595688s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.733702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:25.874107Z","time spent":"3.859519265s","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:29.733973Z","caller":"traceutil/trace.go:171","msg":"trace[836169646] linearizableReadLoop","detail":"{readStateIndex:1787; appliedIndex:1781; }","duration":"3.727441255s","start":"2026-04-20T02:08:26.006502Z","end":"2026-04-20T02:08:29.733943Z","steps":["trace[836169646] 'read index received' (duration: 3.72136892s)","trace[836169646] 'applied index is now lower than readState.Index' (duration: 6.070975ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:29.73436Z","caller":"traceutil/trace.go:171","msg":"trace[757119408] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"3.858400183s","start":"2026-04-20T02:08:25.875938Z","end":"2026-04-20T02:08:29.734338Z","steps":["trace[757119408] 'process raft request' (duration: 3.856908985s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.73441Z","caller":"traceutil/trace.go:171","msg":"trace[1323676913] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"398.003083ms","start":"2026-04-20T02:08:29.336391Z","end":"2026-04-20T02:08:29.734394Z","steps":["trace[1323676913] 'process raft request' (duration: 397.491043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.734489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:25.875923Z","time spent":"3.858484445s","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3223,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T02:08:29.734517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:29.336358Z","time spent":"398.085004ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:29.73467Z","caller":"traceutil/trace.go:171","msg":"trace[1065464177] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"1.861955049s","start":"2026-04-20T02:08:27.872704Z","end":"2026-04-20T02:08:29.734659Z","steps":["trace[1065464177] 'process raft request' (duration: 1.861061312s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.734716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.872675Z","time spent":"1.862020941s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:29.734872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.728367231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T02:08:29.734932Z","caller":"traceutil/trace.go:171","msg":"trace[1914855039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1732; }","duration":"3.728453883s","start":"2026-04-20T02:08:26.006457Z","end":"2026-04-20T02:08:29.734911Z","steps":["trace[1914855039] 'agreement among raft nodes before linearized reading' (duration: 3.72826881s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.734978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:26.006436Z","time spent":"3.728530265s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T02:08:29.735125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.667825302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-20T02:08:29.735153Z","caller":"traceutil/trace.go:171","msg":"trace[1818208506] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1732; }","duration":"3.667871073s","start":"2026-04-20T02:08:26.067272Z","end":"2026-04-20T02:08:29.735143Z","steps":["trace[1818208506] 'agreement among raft nodes before linearized reading' (duration: 3.667797611s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.73517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:26.06726Z","time spent":"3.667906143s","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T02:08:29.735282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00947633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T02:08:29.735303Z","caller":"traceutil/trace.go:171","msg":"trace[74457755] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1732; }","duration":"1.009546482s","start":"2026-04-20T02:08:28.72575Z","end":"2026-04-20T02:08:29.735297Z","steps":["trace[74457755] 'agreement among raft nodes before linearized reading' (duration: 1.009491421s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.735317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:28.725713Z","time spent":"1.009600283s","remote":"127.0.0.1:54338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "} {"level":"warn","ts":"2026-04-20T02:08:29.735333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481607859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:29.735378Z","caller":"traceutil/trace.go:171","msg":"trace[1888978143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1732; }","duration":"1.481686111s","start":"2026-04-20T02:08:28.253682Z","end":"2026-04-20T02:08:29.735368Z","steps":["trace[1888978143] 'agreement among raft nodes before linearized reading' (duration: 1.48161902s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.735414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:28.25366Z","time spent":"1.481747932s","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T02:08:29.735431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.374820817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:29.735539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507302074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:29.735545Z","caller":"traceutil/trace.go:171","msg":"trace[1222859255] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1732; }","duration":"2.374965061s","start":"2026-04-20T02:08:27.360552Z","end":"2026-04-20T02:08:29.735517Z","steps":["trace[1222859255] 'agreement among raft nodes before linearized reading' (duration: 2.374753907s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.735587Z","caller":"traceutil/trace.go:171","msg":"trace[146544452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1732; }","duration":"1.507368475s","start":"2026-04-20T02:08:28.228202Z","end":"2026-04-20T02:08:29.73557Z","steps":["trace[146544452] 'agreement among raft nodes before linearized reading' (duration: 1.507310354s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.735602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.36053Z","time spent":"2.375061072s","remote":"127.0.0.1:33206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:08:29.735629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:28.228179Z","time spent":"1.507442087s","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T02:08:29.735668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464068489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:08:29.735711Z","caller":"traceutil/trace.go:171","msg":"trace[435667296] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1732; }","duration":"1.464161521s","start":"2026-04-20T02:08:28.271536Z","end":"2026-04-20T02:08:29.735698Z","steps":["trace[435667296] 'agreement among raft nodes before linearized reading' (duration: 1.4640949s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.735749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:28.271519Z","time spent":"1.464222672s","remote":"127.0.0.1:54722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:08:29.735821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.821580208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T02:08:29.735871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.113754877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:29.735916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7553126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:08:29.735991Z","caller":"traceutil/trace.go:171","msg":"trace[1622049626] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1732; }","duration":"1.755417081s","start":"2026-04-20T02:08:27.980562Z","end":"2026-04-20T02:08:29.735979Z","steps":["trace[1622049626] 'agreement among raft nodes before linearized reading' (duration: 1.755301099s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.736026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.980544Z","time spent":"1.755472943s","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":19,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:08:29.735969Z","caller":"traceutil/trace.go:171","msg":"trace[1140781248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1732; }","duration":"1.821696901s","start":"2026-04-20T02:08:27.914193Z","end":"2026-04-20T02:08:29.735889Z","steps":["trace[1140781248] 'agreement among raft nodes before linearized reading' (duration: 1.821155101s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.735922Z","caller":"traceutil/trace.go:171","msg":"trace[1642223308] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1732; }","duration":"2.113835738s","start":"2026-04-20T02:08:27.622069Z","end":"2026-04-20T02:08:29.735905Z","steps":["trace[1642223308] 'agreement among raft nodes before linearized reading' (duration: 2.113761096s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.735608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048221009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:29.736174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.622044Z","time spent":"2.114113143s","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:08:29.736229Z","caller":"traceutil/trace.go:171","msg":"trace[325289363] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1732; }","duration":"1.048895372s","start":"2026-04-20T02:08:28.687325Z","end":"2026-04-20T02:08:29.73622Z","steps":["trace[325289363] 'agreement among raft nodes before linearized reading' (duration: 1.04823994s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.736231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.115468259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:08:29.736283Z","caller":"traceutil/trace.go:171","msg":"trace[695531490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1732; }","duration":"2.11556439s","start":"2026-04-20T02:08:27.620708Z","end":"2026-04-20T02:08:29.736272Z","steps":["trace[695531490] 'agreement among raft nodes before linearized reading' (duration: 2.115266544s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.736142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.914179Z","time spent":"1.821950055s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T02:08:29.736254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:28.687284Z","time spent":"1.048961654s","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:08:29.736321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.620683Z","time spent":"2.115631041s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:08:29.735457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.421451837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:08:29.736708Z","caller":"traceutil/trace.go:171","msg":"trace[587205252] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1732; }","duration":"2.42275013s","start":"2026-04-20T02:08:27.313938Z","end":"2026-04-20T02:08:29.736688Z","steps":["trace[587205252] 'agreement among raft nodes before linearized reading' (duration: 2.421410116s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.736758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:27.313913Z","time spent":"2.422830212s","remote":"127.0.0.1:54432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:08:29.737833Z","caller":"traceutil/trace.go:171","msg":"trace[1874046928] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"3.858697519s","start":"2026-04-20T02:08:25.874102Z","end":"2026-04-20T02:08:29.7328Z","steps":["trace[1874046928] 'process raft request' (duration: 3.858506016s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.738047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:25.874089Z","time spent":"3.863851297s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:35.730126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:36.230307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:36.730649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303198453215349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:37.199722Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.152952364s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:08:37.200255Z","caller":"traceutil/trace.go:171","msg":"trace[731009939] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"2.153559435s","start":"2026-04-20T02:08:35.046655Z","end":"2026-04-20T02:08:37.200214Z","steps":["trace[731009939] 'process raft request' (duration: 2.153338661s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.200493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.04663Z","time spent":"2.153732849s","remote":"127.0.0.1:54384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:37.213101Z","caller":"traceutil/trace.go:171","msg":"trace[1085505916] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1910; }","duration":"1.983863857s","start":"2026-04-20T02:08:35.229208Z","end":"2026-04-20T02:08:37.213072Z","steps":["trace[1085505916] 'read index received' (duration: 1.97078745s)","trace[1085505916] 'applied index is now lower than readState.Index' (duration: 13.075237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:37.213427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984020661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:37.213468Z","caller":"traceutil/trace.go:171","msg":"trace[1847014688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"1.984331566s","start":"2026-04-20T02:08:35.229124Z","end":"2026-04-20T02:08:37.213456Z","steps":["trace[1847014688] 'agreement among raft nodes before linearized reading' (duration: 1.984056681s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.213428Z","caller":"traceutil/trace.go:171","msg":"trace[658834549] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"1.454380586s","start":"2026-04-20T02:08:35.758819Z","end":"2026-04-20T02:08:37.2132Z","steps":["trace[658834549] 'process raft request' (duration: 1.454100251s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.213669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.612383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:9609"} {"level":"info","ts":"2026-04-20T02:08:37.213711Z","caller":"traceutil/trace.go:171","msg":"trace[1962458296] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1853; }","duration":"991.689345ms","start":"2026-04-20T02:08:36.222013Z","end":"2026-04-20T02:08:37.213703Z","steps":["trace[1962458296] 'agreement among raft nodes before linearized reading' (duration: 991.541682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.213712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.758796Z","time spent":"1.454791134s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:37.213745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:36.221988Z","time spent":"991.751375ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":9633,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T02:08:37.213743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26096601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-04-20T02:08:37.213737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413795192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-20T02:08:37.213802Z","caller":"traceutil/trace.go:171","msg":"trace[1980340265] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"1.261072132s","start":"2026-04-20T02:08:35.952712Z","end":"2026-04-20T02:08:37.213784Z","steps":["trace[1980340265] 'agreement among raft nodes before linearized reading' (duration: 1.260871949s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.213801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441259379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T02:08:37.213801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192717274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1715156f-6eea-475e-aeeb-f2625fb2c053\" ","response":"range_response_count:1 size:3913"} {"level":"warn","ts":"2026-04-20T02:08:37.213842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.952686Z","time spent":"1.261147953s","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T02:08:37.21352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.22909Z","time spent":"1.984414828s","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T02:08:37.213893Z","caller":"traceutil/trace.go:171","msg":"trace[1234760206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1853; }","duration":"1.441378731s","start":"2026-04-20T02:08:35.772486Z","end":"2026-04-20T02:08:37.213864Z","steps":["trace[1234760206] 'agreement among raft nodes before linearized reading' (duration: 1.441095155s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.213916Z","caller":"traceutil/trace.go:171","msg":"trace[468532954] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1715156f-6eea-475e-aeeb-f2625fb2c053; range_end:; response_count:1; response_revision:1853; }","duration":"1.192861877s","start":"2026-04-20T02:08:36.021023Z","end":"2026-04-20T02:08:37.213885Z","steps":["trace[468532954] 'agreement among raft nodes before linearized reading' (duration: 1.192581592s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.213938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.772478Z","time spent":"1.441451572s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:08:37.213945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384203994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:37.213975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:36.020965Z","time spent":"1.19299878s","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3937,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1715156f-6eea-475e-aeeb-f2625fb2c053\" "} {"level":"info","ts":"2026-04-20T02:08:37.213991Z","caller":"traceutil/trace.go:171","msg":"trace[519094917] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1853; }","duration":"1.384301265s","start":"2026-04-20T02:08:35.829668Z","end":"2026-04-20T02:08:37.213969Z","steps":["trace[519094917] 'agreement among raft nodes before linearized reading' (duration: 1.384215833s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.818739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"warn","ts":"2026-04-20T02:08:37.214042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.829651Z","time spent":"1.384380197s","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:08:37.214009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.790917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:37.214076Z","caller":"traceutil/trace.go:171","msg":"trace[1681486780] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1853; }","duration":"954.8942ms","start":"2026-04-20T02:08:36.259163Z","end":"2026-04-20T02:08:37.214057Z","steps":["trace[1681486780] 'agreement among raft nodes before linearized reading' (duration: 954.711197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:36.259106Z","time spent":"955.003722ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T02:08:37.214118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425130854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-20T02:08:37.214117Z","caller":"traceutil/trace.go:171","msg":"trace[396215343] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1853; }","duration":"677.95799ms","start":"2026-04-20T02:08:36.536133Z","end":"2026-04-20T02:08:37.214091Z","steps":["trace[396215343] 'agreement among raft nodes before linearized reading' (duration: 677.749256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439889453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T02:08:37.214155Z","caller":"traceutil/trace.go:171","msg":"trace[958830742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"1.425183535s","start":"2026-04-20T02:08:35.78896Z","end":"2026-04-20T02:08:37.214143Z","steps":["trace[958830742] 'agreement among raft nodes before linearized reading' (duration: 1.425080633s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:36.536114Z","time spent":"678.048672ms","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:08:37.21418Z","caller":"traceutil/trace.go:171","msg":"trace[1141495573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1853; }","duration":"1.439958724s","start":"2026-04-20T02:08:35.77421Z","end":"2026-04-20T02:08:37.214169Z","steps":["trace[1141495573] 'agreement among raft nodes before linearized reading' (duration: 1.439854272s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.788949Z","time spent":"1.425236916s","remote":"127.0.0.1:59902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4615,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T02:08:37.214211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.774198Z","time spent":"1.440006875s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T02:08:37.214487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450285898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-20T02:08:37.214527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452375188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T02:08:37.214554Z","caller":"traceutil/trace.go:171","msg":"trace[567215417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1853; }","duration":"1.452437469s","start":"2026-04-20T02:08:35.762109Z","end":"2026-04-20T02:08:37.214546Z","steps":["trace[567215417] 'agreement among raft nodes before linearized reading' (duration: 1.452357888s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.76209Z","time spent":"1.45248388s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T02:08:37.213848Z","caller":"traceutil/trace.go:171","msg":"trace[885778916] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1853; }","duration":"1.413928384s","start":"2026-04-20T02:08:35.799889Z","end":"2026-04-20T02:08:37.213818Z","steps":["trace[885778916] 'agreement among raft nodes before linearized reading' (duration: 1.413625418s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425295657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T02:08:37.214635Z","caller":"traceutil/trace.go:171","msg":"trace[1011677245] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1853; }","duration":"1.425359629s","start":"2026-04-20T02:08:35.789267Z","end":"2026-04-20T02:08:37.214626Z","steps":["trace[1011677245] 'agreement among raft nodes before linearized reading' (duration: 1.425270167s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449394852s","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-04-20T02:08:37.214643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.799882Z","time spent":"1.414746399s","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T02:08:37.214667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.789248Z","time spent":"1.42541154s","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T02:08:37.214677Z","caller":"traceutil/trace.go:171","msg":"trace[1464170605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1853; }","duration":"1.449510475s","start":"2026-04-20T02:08:35.765153Z","end":"2026-04-20T02:08:37.214664Z","steps":["trace[1464170605] 'agreement among raft nodes before linearized reading' (duration: 1.449336111s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.765146Z","time spent":"1.449577247s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T02:08:37.214823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.938785898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1715156f-6eea-475e-aeeb-f2625fb2c053\" ","response":"range_response_count:1 size:3913"} {"level":"info","ts":"2026-04-20T02:08:37.214868Z","caller":"traceutil/trace.go:171","msg":"trace[307831903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1715156f-6eea-475e-aeeb-f2625fb2c053; range_end:; response_count:1; response_revision:1853; }","duration":"1.93887304s","start":"2026-04-20T02:08:35.275982Z","end":"2026-04-20T02:08:37.214856Z","steps":["trace[307831903] 'agreement among raft nodes before linearized reading' (duration: 1.937472583s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.214909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.275932Z","time spent":"1.938969422s","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3937,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1715156f-6eea-475e-aeeb-f2625fb2c053\" "} {"level":"info","ts":"2026-04-20T02:08:37.214543Z","caller":"traceutil/trace.go:171","msg":"trace[1227153183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"1.45037778s","start":"2026-04-20T02:08:35.764145Z","end":"2026-04-20T02:08:37.214522Z","steps":["trace[1227153183] 'agreement among raft nodes before linearized reading' (duration: 1.450011393s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.21598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:35.764125Z","time spent":"1.451835888s","remote":"127.0.0.1:54556","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-04-20T02:08:37.380689Z","caller":"traceutil/trace.go:171","msg":"trace[87646878] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"161.192158ms","start":"2026-04-20T02:08:37.219463Z","end":"2026-04-20T02:08:37.380656Z","steps":["trace[87646878] 'process raft request' (duration: 69.998519ms)","trace[87646878] 'compare' (duration: 91.083827ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:37.394498Z","caller":"traceutil/trace.go:171","msg":"trace[2022027666] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"174.612732ms","start":"2026-04-20T02:08:37.219846Z","end":"2026-04-20T02:08:37.394459Z","steps":["trace[2022027666] 'process raft request' (duration: 174.51146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.394542Z","caller":"traceutil/trace.go:171","msg":"trace[1388462382] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"174.882347ms","start":"2026-04-20T02:08:37.21961Z","end":"2026-04-20T02:08:37.394492Z","steps":["trace[1388462382] 'process raft request' (duration: 174.561812ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.394704Z","caller":"traceutil/trace.go:171","msg":"trace[366147099] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"174.643783ms","start":"2026-04-20T02:08:37.220043Z","end":"2026-04-20T02:08:37.394687Z","steps":["trace[366147099] 'process raft request' (duration: 174.359478ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.39471Z","caller":"traceutil/trace.go:171","msg":"trace[361363158] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"174.585111ms","start":"2026-04-20T02:08:37.220107Z","end":"2026-04-20T02:08:37.394692Z","steps":["trace[361363158] 'process raft request' (duration: 174.454399ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.394943Z","caller":"traceutil/trace.go:171","msg":"trace[143214736] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"174.322416ms","start":"2026-04-20T02:08:37.220606Z","end":"2026-04-20T02:08:37.394929Z","steps":["trace[143214736] 'process raft request' (duration: 174.050181ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.394955Z","caller":"traceutil/trace.go:171","msg":"trace[298042478] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"172.3785ms","start":"2026-04-20T02:08:37.222548Z","end":"2026-04-20T02:08:37.394926Z","steps":["trace[298042478] 'process raft request' (duration: 172.238197ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.395072Z","caller":"traceutil/trace.go:171","msg":"trace[484262257] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"170.126007ms","start":"2026-04-20T02:08:37.224935Z","end":"2026-04-20T02:08:37.395061Z","steps":["trace[484262257] 'process raft request' (duration: 169.963274ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.395048Z","caller":"traceutil/trace.go:171","msg":"trace[1874101841] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1912; }","duration":"169.72108ms","start":"2026-04-20T02:08:37.225301Z","end":"2026-04-20T02:08:37.395023Z","steps":["trace[1874101841] 'read index received' (duration: 64.17787ms)","trace[1874101841] 'applied index is now lower than readState.Index' (duration: 105.54223ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:37.395108Z","caller":"traceutil/trace.go:171","msg":"trace[681571586] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"135.416383ms","start":"2026-04-20T02:08:37.259678Z","end":"2026-04-20T02:08:37.395094Z","steps":["trace[681571586] 'process raft request' (duration: 135.28031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:37.395178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.860303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:37.395216Z","caller":"traceutil/trace.go:171","msg":"trace[434348611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"169.927744ms","start":"2026-04-20T02:08:37.225278Z","end":"2026-04-20T02:08:37.395206Z","steps":["trace[434348611] 'agreement among raft nodes before linearized reading' (duration: 169.837122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.394976Z","caller":"traceutil/trace.go:171","msg":"trace[652374273] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"171.759477ms","start":"2026-04-20T02:08:37.223202Z","end":"2026-04-20T02:08:37.394961Z","steps":["trace[652374273] 'process raft request' (duration: 171.660635ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.589866Z","caller":"traceutil/trace.go:171","msg":"trace[512722136] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1865; }","duration":"184.578948ms","start":"2026-04-20T02:08:37.405263Z","end":"2026-04-20T02:08:37.589842Z","steps":["trace[512722136] 'process raft request' (duration: 184.528467ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:37.589956Z","caller":"traceutil/trace.go:171","msg":"trace[712334777] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"185.6254ms","start":"2026-04-20T02:08:37.404285Z","end":"2026-04-20T02:08:37.58991Z","steps":["trace[712334777] 'process raft request' (duration: 114.300895ms)","trace[712334777] 'compare' (duration: 71.06859ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:42.825303Z","caller":"traceutil/trace.go:171","msg":"trace[10872567] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"119.017253ms","start":"2026-04-20T02:08:42.706258Z","end":"2026-04-20T02:08:42.825276Z","steps":["trace[10872567] 'process raft request' (duration: 118.890221ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:09.694409Z","caller":"traceutil/trace.go:171","msg":"trace[187203596] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"113.770865ms","start":"2026-04-20T02:09:09.580593Z","end":"2026-04-20T02:09:09.694364Z","steps":["trace[187203596] 'process raft request' (duration: 75.120866ms)","trace[187203596] 'compare' (duration: 38.441565ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:13.604822Z","caller":"traceutil/trace.go:171","msg":"trace[313780633] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2216; }","duration":"124.93114ms","start":"2026-04-20T02:09:13.479861Z","end":"2026-04-20T02:09:13.604792Z","steps":["trace[313780633] 'read index received' (duration: 58.067442ms)","trace[313780633] 'applied index is now lower than readState.Index' (duration: 66.862698ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:13.604893Z","caller":"traceutil/trace.go:171","msg":"trace[344263197] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"152.06667ms","start":"2026-04-20T02:09:13.452795Z","end":"2026-04-20T02:09:13.604861Z","steps":["trace[344263197] 'process raft request' (duration: 85.204823ms)","trace[344263197] 'compare' (duration: 66.642843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:13.605238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.355528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-20T02:09:13.60533Z","caller":"traceutil/trace.go:171","msg":"trace[1920413403] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"125.49673ms","start":"2026-04-20T02:09:13.479804Z","end":"2026-04-20T02:09:13.605301Z","steps":["trace[1920413403] 'agreement among raft nodes before linearized reading' (duration: 125.153964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:13.81275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.894847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:09:13.812874Z","caller":"traceutil/trace.go:171","msg":"trace[1659362669] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2219; }","duration":"130.716628ms","start":"2026-04-20T02:09:13.682135Z","end":"2026-04-20T02:09:13.812851Z","steps":["trace[1659362669] 'read index received' (duration: 4.365393ms)","trace[1659362669] 'applied index is now lower than readState.Index' (duration: 126.350035ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:13.812979Z","caller":"traceutil/trace.go:171","msg":"trace[595966594] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"139.87673ms","start":"2026-04-20T02:09:13.67309Z","end":"2026-04-20T02:09:13.812967Z","steps":["trace[595966594] 'process raft request' (duration: 37.647238ms)","trace[595966594] 'compare' (duration: 101.584511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:13.813264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.120376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T02:09:13.813314Z","caller":"traceutil/trace.go:171","msg":"trace[1956422316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2147; }","duration":"131.202758ms","start":"2026-04-20T02:09:13.682097Z","end":"2026-04-20T02:09:13.8133Z","steps":["trace[1956422316] 'agreement among raft nodes before linearized reading' (duration: 131.039045ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:53.986708Z","caller":"traceutil/trace.go:171","msg":"trace[1413437630] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"149.693716ms","start":"2026-04-20T02:09:53.83699Z","end":"2026-04-20T02:09:53.986684Z","steps":["trace[1413437630] 'process raft request' (duration: 149.580444ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:53.988459Z","caller":"traceutil/trace.go:171","msg":"trace[33561589] linearizableReadLoop","detail":"{readStateIndex:2561; appliedIndex:2560; }","duration":"142.682593ms","start":"2026-04-20T02:09:53.845752Z","end":"2026-04-20T02:09:53.988435Z","steps":["trace[33561589] 'read index received' (duration: 140.928161ms)","trace[33561589] 'applied index is now lower than readState.Index' (duration: 1.753492ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:53.988508Z","caller":"traceutil/trace.go:171","msg":"trace[888614052] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"151.338066ms","start":"2026-04-20T02:09:53.83714Z","end":"2026-04-20T02:09:53.988478Z","steps":["trace[888614052] 'process raft request' (duration: 151.161423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:53.988659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.885237ms","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-04-20T02:09:53.98875Z","caller":"traceutil/trace.go:171","msg":"trace[1685824175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2469; }","duration":"143.01232ms","start":"2026-04-20T02:09:53.84571Z","end":"2026-04-20T02:09:53.988722Z","steps":["trace[1685824175] 'agreement among raft nodes before linearized reading' (duration: 142.802446ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:24.307704Z","caller":"traceutil/trace.go:171","msg":"trace[390126504] transaction","detail":"{read_only:false; response_revision:2683; number_of_response:1; }","duration":"103.741909ms","start":"2026-04-20T02:10:24.203909Z","end":"2026-04-20T02:10:24.307651Z","steps":["trace[390126504] 'process raft request' (duration: 91.054331ms)","trace[390126504] 'compare' (duration: 12.24405ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:10:29.923523Z","caller":"traceutil/trace.go:171","msg":"trace[225815698] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"186.712709ms","start":"2026-04-20T02:10:29.73677Z","end":"2026-04-20T02:10:29.923482Z","steps":["trace[225815698] 'process raft request' (duration: 186.508645ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:41.261451Z","caller":"traceutil/trace.go:171","msg":"trace[507201021] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"371.961348ms","start":"2026-04-20T02:10:40.889463Z","end":"2026-04-20T02:10:41.261424Z","steps":["trace[507201021] 'process raft request' (duration: 371.793525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:10:41.261638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:10:40.889451Z","time spent":"372.088781ms","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:10:41.267028Z","caller":"traceutil/trace.go:171","msg":"trace[2071649268] linearizableReadLoop","detail":"{readStateIndex:2905; appliedIndex:2903; }","duration":"256.546209ms","start":"2026-04-20T02:10:41.010456Z","end":"2026-04-20T02:10:41.267002Z","steps":["trace[2071649268] 'read index received' (duration: 250.883483ms)","trace[2071649268] 'applied index is now lower than readState.Index' (duration: 5.661066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:10:41.267283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.812575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T02:10:41.26737Z","caller":"traceutil/trace.go:171","msg":"trace[261440355] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2793; }","duration":"256.966829ms","start":"2026-04-20T02:10:41.01038Z","end":"2026-04-20T02:10:41.267347Z","steps":["trace[261440355] 'agreement among raft nodes before linearized reading' (duration: 256.754104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:41.267388Z","caller":"traceutil/trace.go:171","msg":"trace[821861520] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"344.950182ms","start":"2026-04-20T02:10:40.922422Z","end":"2026-04-20T02:10:41.267372Z","steps":["trace[821861520] 'process raft request' (duration: 344.422571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:10:41.267502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:10:40.922381Z","time spent":"345.039284ms","remote":"127.0.0.1:54762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:10:42.932592Z","caller":"traceutil/trace.go:171","msg":"trace[662025371] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"118.082799ms","start":"2026-04-20T02:10:42.814459Z","end":"2026-04-20T02:10:42.932542Z","steps":["trace[662025371] 'process raft request' (duration: 117.727442ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:42.933763Z","caller":"traceutil/trace.go:171","msg":"trace[2013915576] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"117.670411ms","start":"2026-04-20T02:10:42.816069Z","end":"2026-04-20T02:10:42.933739Z","steps":["trace[2013915576] 'process raft request' (duration: 117.035429ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:42.934024Z","caller":"traceutil/trace.go:171","msg":"trace[2135825491] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"117.904005ms","start":"2026-04-20T02:10:42.816085Z","end":"2026-04-20T02:10:42.933989Z","steps":["trace[2135825491] 'process raft request' (duration: 117.199872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:42.934165Z","caller":"traceutil/trace.go:171","msg":"trace[1329756707] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"117.926337ms","start":"2026-04-20T02:10:42.816232Z","end":"2026-04-20T02:10:42.934158Z","steps":["trace[1329756707] 'process raft request' (duration: 117.09827ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:42.934283Z","caller":"traceutil/trace.go:171","msg":"trace[1554924734] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"117.466157ms","start":"2026-04-20T02:10:42.816809Z","end":"2026-04-20T02:10:42.934276Z","steps":["trace[1554924734] 'process raft request' (duration: 116.560589ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:12:44.641878Z","caller":"traceutil/trace.go:171","msg":"trace[1542660890] transaction","detail":"{read_only:false; response_revision:4390; number_of_response:1; }","duration":"132.291596ms","start":"2026-04-20T02:12:44.509553Z","end":"2026-04-20T02:12:44.641845Z","steps":["trace[1542660890] 'process raft request' (duration: 131.94549ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:12:48.041093Z","caller":"traceutil/trace.go:171","msg":"trace[825497229] transaction","detail":"{read_only:false; response_revision:4504; number_of_response:1; }","duration":"118.526026ms","start":"2026-04-20T02:12:47.922524Z","end":"2026-04-20T02:12:48.04105Z","steps":["trace[825497229] 'process raft request' (duration: 118.291461ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:12:59.918903Z","caller":"traceutil/trace.go:171","msg":"trace[2069737302] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"170.111576ms","start":"2026-04-20T02:12:59.748755Z","end":"2026-04-20T02:12:59.918866Z","steps":["trace[2069737302] 'process raft request' (duration: 87.214989ms)","trace[2069737302] 'compare' (duration: 82.733554ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:13:00.001143Z","caller":"traceutil/trace.go:171","msg":"trace[1924473382] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"211.495622ms","start":"2026-04-20T02:12:59.789618Z","end":"2026-04-20T02:13:00.001113Z","steps":["trace[1924473382] 'process raft request' (duration: 210.356201ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:13:00.244929Z","caller":"traceutil/trace.go:171","msg":"trace[1528624130] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"238.254152ms","start":"2026-04-20T02:13:00.00664Z","end":"2026-04-20T02:13:00.244894Z","steps":["trace[1528624130] 'process raft request' (duration: 217.590829ms)","trace[1528624130] 'compare' (duration: 20.459549ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:13:00.326828Z","caller":"traceutil/trace.go:171","msg":"trace[1280796357] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"320.074928ms","start":"2026-04-20T02:13:00.006709Z","end":"2026-04-20T02:13:00.326784Z","steps":["trace[1280796357] 'process raft request' (duration: 319.856503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:00.326998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:00.006684Z","time spent":"320.224561ms","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:13:00.327386Z","caller":"traceutil/trace.go:171","msg":"trace[1430271623] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"320.513008ms","start":"2026-04-20T02:13:00.006808Z","end":"2026-04-20T02:13:00.327321Z","steps":["trace[1430271623] 'process raft request' (duration: 319.914585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:00.327579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:00.006797Z","time spent":"320.66923ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:13:00.327572Z","caller":"traceutil/trace.go:171","msg":"trace[104842131] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4947; }","duration":"319.61568ms","start":"2026-04-20T02:13:00.007873Z","end":"2026-04-20T02:13:00.327489Z","steps":["trace[104842131] 'read index received' (duration: 216.453587ms)","trace[104842131] 'applied index is now lower than readState.Index' (duration: 103.160853ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:13:00.327618Z","caller":"traceutil/trace.go:171","msg":"trace[529452027] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"320.389705ms","start":"2026-04-20T02:13:00.007202Z","end":"2026-04-20T02:13:00.327592Z","steps":["trace[529452027] 'process raft request' (duration: 319.942766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:00.327921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:00.007194Z","time spent":"320.595798ms","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:13:00.327928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.039898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:14644"} {"level":"info","ts":"2026-04-20T02:13:00.328111Z","caller":"traceutil/trace.go:171","msg":"trace[1274838721] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4748; }","duration":"320.277572ms","start":"2026-04-20T02:13:00.007812Z","end":"2026-04-20T02:13:00.328089Z","steps":["trace[1274838721] 'agreement among raft nodes before linearized reading' (duration: 319.951346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:00.328206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:00.007803Z","time spent":"320.387304ms","remote":"127.0.0.1:54490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":14668,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T02:13:07.056979Z","caller":"traceutil/trace.go:171","msg":"trace[164118386] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"168.110768ms","start":"2026-04-20T02:13:06.888843Z","end":"2026-04-20T02:13:07.056954Z","steps":["trace[164118386] 'process raft request' (duration: 167.928234ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:13:07.191988Z","caller":"traceutil/trace.go:171","msg":"trace[817888714] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5111; }","duration":"217.768792ms","start":"2026-04-20T02:13:06.974192Z","end":"2026-04-20T02:13:07.191961Z","steps":["trace[817888714] 'read index received' (duration: 82.654723ms)","trace[817888714] 'applied index is now lower than readState.Index' (duration: 135.113019ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:13:07.19219Z","caller":"traceutil/trace.go:171","msg":"trace[1405196104] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"301.79128ms","start":"2026-04-20T02:13:06.890385Z","end":"2026-04-20T02:13:07.192176Z","steps":["trace[1405196104] 'process raft request' (duration: 301.390583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:07.192415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:06.890368Z","time spent":"301.912752ms","remote":"127.0.0.1:54420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:13:07.192517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.943975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qrccr\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T02:13:07.192571Z","caller":"traceutil/trace.go:171","msg":"trace[96056498] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qrccr; range_end:; response_count:1; response_revision:4905; }","duration":"218.034387ms","start":"2026-04-20T02:13:06.974521Z","end":"2026-04-20T02:13:07.192556Z","steps":["trace[96056498] 'agreement among raft nodes before linearized reading' (duration: 217.823163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:07.192621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.444314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ef3e5e0-8a5e-4187-9dea-6b23bd9f9996\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-04-20T02:13:07.19262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.202684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-20T02:13:07.192688Z","caller":"traceutil/trace.go:171","msg":"trace[2103440730] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ef3e5e0-8a5e-4187-9dea-6b23bd9f9996; range_end:; response_count:1; response_revision:4905; }","duration":"157.555126ms","start":"2026-04-20T02:13:07.035113Z","end":"2026-04-20T02:13:07.192668Z","steps":["trace[2103440730] 'agreement among raft nodes before linearized reading' (duration: 157.348023ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:13:07.192727Z","caller":"traceutil/trace.go:171","msg":"trace[2110025182] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4905; }","duration":"132.339147ms","start":"2026-04-20T02:13:07.060351Z","end":"2026-04-20T02:13:07.19269Z","steps":["trace[2110025182] 'agreement among raft nodes before linearized reading' (duration: 132.068172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:07.192863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.648208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T02:13:07.193114Z","caller":"traceutil/trace.go:171","msg":"trace[1691156872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4905; }","duration":"218.864663ms","start":"2026-04-20T02:13:06.974129Z","end":"2026-04-20T02:13:07.192994Z","steps":["trace[1691156872] 'agreement among raft nodes before linearized reading' (duration: 218.501326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:16:25.816451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3269} {"level":"info","ts":"2026-04-20T02:16:25.90078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3269,"took":"83.139798ms","hash":2237091026,"current-db-size-bytes":61480960,"current-db-size":"62 MB","current-db-size-in-use-bytes":33976320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T02:16:25.900836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2237091026,"revision":3269,"compact-revision":-1} {"level":"info","ts":"2026-04-20T02:18:57.476286Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000210d.wal"} {"level":"info","ts":"2026-04-20T02:18:59.818125Z","caller":"traceutil/trace.go:171","msg":"trace[658752991] linearizableReadLoop","detail":"{readStateIndex:8483; appliedIndex:8481; }","duration":"142.453397ms","start":"2026-04-20T02:18:59.675628Z","end":"2026-04-20T02:18:59.818082Z","steps":["trace[658752991] 'read index received' (duration: 18.922245ms)","trace[658752991] 'applied index is now lower than readState.Index' (duration: 123.530142ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:18:59.818246Z","caller":"traceutil/trace.go:171","msg":"trace[1650669920] transaction","detail":"{read_only:false; response_revision:8121; number_of_response:1; }","duration":"172.883209ms","start":"2026-04-20T02:18:59.645353Z","end":"2026-04-20T02:18:59.818236Z","steps":["trace[1650669920] 'process raft request' (duration: 169.350293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:18:59.81836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.711432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-20T02:18:59.818456Z","caller":"traceutil/trace.go:171","msg":"trace[108479454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8121; }","duration":"142.840795ms","start":"2026-04-20T02:18:59.675597Z","end":"2026-04-20T02:18:59.818438Z","steps":["trace[108479454] 'agreement among raft nodes before linearized reading' (duration: 142.635711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:18:59.818786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.996219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T02:18:59.818838Z","caller":"traceutil/trace.go:171","msg":"trace[37084557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8122; }","duration":"142.073001ms","start":"2026-04-20T02:18:59.676753Z","end":"2026-04-20T02:18:59.818826Z","steps":["trace[37084557] 'agreement among raft nodes before linearized reading' (duration: 141.921528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:18:59.818876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.702673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T02:18:59.818931Z","caller":"traceutil/trace.go:171","msg":"trace[1481270353] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8122; }","duration":"141.778236ms","start":"2026-04-20T02:18:59.677137Z","end":"2026-04-20T02:18:59.818915Z","steps":["trace[1481270353] 'agreement among raft nodes before linearized reading' (duration: 141.618553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:19:13.458761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.87176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:19:13.458901Z","caller":"traceutil/trace.go:171","msg":"trace[1645471902] linearizableReadLoop","detail":"{readStateIndex:8578; appliedIndex:8577; }","duration":"212.565055ms","start":"2026-04-20T02:19:13.246317Z","end":"2026-04-20T02:19:13.458882Z","steps":["trace[1645471902] 'read index received' (duration: 33.56µs)","trace[1645471902] 'applied index is now lower than readState.Index' (duration: 212.529255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:19:13.459163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.84183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qrccr\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T02:19:13.459131Z","caller":"traceutil/trace.go:171","msg":"trace[1723209939] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"293.67108ms","start":"2026-04-20T02:19:13.165424Z","end":"2026-04-20T02:19:13.459095Z","steps":["trace[1723209939] 'process raft request' (duration: 46.368502ms)","trace[1723209939] 'compare' (duration: 246.733517ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:13.459204Z","caller":"traceutil/trace.go:171","msg":"trace[1188606918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qrccr; range_end:; response_count:1; response_revision:8211; }","duration":"212.914661ms","start":"2026-04-20T02:19:13.246281Z","end":"2026-04-20T02:19:13.459195Z","steps":["trace[1188606918] 'agreement among raft nodes before linearized reading' (duration: 212.765759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:19:13.459327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.731541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:19:13.459471Z","caller":"traceutil/trace.go:171","msg":"trace[412185386] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8211; }","duration":"119.947364ms","start":"2026-04-20T02:19:13.3395Z","end":"2026-04-20T02:19:13.459448Z","steps":["trace[412185386] 'agreement among raft nodes before linearized reading' (duration: 119.586487ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:19:54.243842Z","caller":"traceutil/trace.go:171","msg":"trace[1821875056] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"109.264343ms","start":"2026-04-20T02:19:54.13454Z","end":"2026-04-20T02:19:54.243804Z","steps":["trace[1821875056] 'process raft request' (duration: 95.411603ms)","trace[1821875056] 'compare' (duration: 13.704338ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:54.813869Z","caller":"traceutil/trace.go:171","msg":"trace[1989779512] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"123.416201ms","start":"2026-04-20T02:19:54.690417Z","end":"2026-04-20T02:19:54.813833Z","steps":["trace[1989779512] 'process raft request' (duration: 123.185106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:19:54.932761Z","caller":"traceutil/trace.go:171","msg":"trace[1593162426] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"114.064924ms","start":"2026-04-20T02:19:54.818656Z","end":"2026-04-20T02:19:54.932721Z","steps":["trace[1593162426] 'process raft request' (duration: 73.160014ms)","trace[1593162426] 'compare' (duration: 40.733937ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:54.974968Z","caller":"traceutil/trace.go:171","msg":"trace[1493130013] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"123.691344ms","start":"2026-04-20T02:19:54.851252Z","end":"2026-04-20T02:19:54.974943Z","steps":["trace[1493130013] 'process raft request' (duration: 123.496791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:19:54.974971Z","caller":"traceutil/trace.go:171","msg":"trace[550551530] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"131.641974ms","start":"2026-04-20T02:19:54.843278Z","end":"2026-04-20T02:19:54.97492Z","steps":["trace[550551530] 'process raft request' (duration: 131.305408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:19:54.975284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.664975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rpmtt\" ","response":"range_response_count:1 size:4611"} {"level":"info","ts":"2026-04-20T02:19:54.97539Z","caller":"traceutil/trace.go:171","msg":"trace[2046491923] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rpmtt; range_end:; response_count:1; response_revision:8585; }","duration":"131.839228ms","start":"2026-04-20T02:19:54.843526Z","end":"2026-04-20T02:19:54.975365Z","steps":["trace[2046491923] 'agreement among raft nodes before linearized reading' (duration: 131.547253ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:19:54.974842Z","caller":"traceutil/trace.go:171","msg":"trace[726382418] linearizableReadLoop","detail":"{readStateIndex:8973; appliedIndex:8971; }","duration":"131.216677ms","start":"2026-04-20T02:19:54.843597Z","end":"2026-04-20T02:19:54.974813Z","steps":["trace[726382418] 'read index received' (duration: 48.234607ms)","trace[726382418] 'applied index is now lower than readState.Index' (duration: 82.98082ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:55.801746Z","caller":"traceutil/trace.go:171","msg":"trace[1230973335] linearizableReadLoop","detail":"{readStateIndex:8977; appliedIndex:8976; }","duration":"100.823606ms","start":"2026-04-20T02:19:55.700898Z","end":"2026-04-20T02:19:55.801721Z","steps":["trace[1230973335] 'read index received' (duration: 100.606182ms)","trace[1230973335] 'applied index is now lower than readState.Index' (duration: 216.584µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:55.801851Z","caller":"traceutil/trace.go:171","msg":"trace[649569308] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"152.006546ms","start":"2026-04-20T02:19:55.649812Z","end":"2026-04-20T02:19:55.801819Z","steps":["trace[649569308] 'process raft request' (duration: 151.752782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:19:55.802151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.244753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T02:19:55.802273Z","caller":"traceutil/trace.go:171","msg":"trace[1078441848] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8588; }","duration":"101.347125ms","start":"2026-04-20T02:19:55.700838Z","end":"2026-04-20T02:19:55.802185Z","steps":["trace[1078441848] 'agreement among raft nodes before linearized reading' (duration: 101.012529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:19:56.082637Z","caller":"traceutil/trace.go:171","msg":"trace[728331369] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"107.414528ms","start":"2026-04-20T02:19:55.975184Z","end":"2026-04-20T02:19:56.082599Z","steps":["trace[728331369] 'process raft request' (duration: 66.028371ms)","trace[728331369] 'compare' (duration: 41.164703ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:56.102459Z","caller":"traceutil/trace.go:171","msg":"trace[1187934559] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"124.410279ms","start":"2026-04-20T02:19:55.978018Z","end":"2026-04-20T02:19:56.102429Z","steps":["trace[1187934559] 'process raft request' (duration: 124.251795ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:19:56.264931Z","caller":"traceutil/trace.go:171","msg":"trace[1675621356] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"121.150138ms","start":"2026-04-20T02:19:56.14374Z","end":"2026-04-20T02:19:56.26489Z","steps":["trace[1675621356] 'process raft request' (duration: 77.591299ms)","trace[1675621356] 'compare' (duration: 43.353894ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:19:56.317683Z","caller":"traceutil/trace.go:171","msg":"trace[487062982] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"173.784416ms","start":"2026-04-20T02:19:56.143855Z","end":"2026-04-20T02:19:56.31764Z","steps":["trace[487062982] 'process raft request' (duration: 173.492901ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:21:25.82339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6635} {"level":"info","ts":"2026-04-20T02:21:25.934307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6635,"took":"107.618548ms","hash":3822674868,"current-db-size-bytes":61480960,"current-db-size":"62 MB","current-db-size-in-use-bytes":26943488,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-20T02:21:25.934398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3822674868,"revision":6635,"compact-revision":3269} {"level":"info","ts":"2026-04-20T02:21:32.536211Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T02:21:32.541381Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T02:21:32.541491Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T02:25:03.267675Z","caller":"traceutil/trace.go:171","msg":"trace[1991836665] transaction","detail":"{read_only:false; response_revision:11349; number_of_response:1; }","duration":"108.474714ms","start":"2026-04-20T02:25:03.159182Z","end":"2026-04-20T02:25:03.267656Z","steps":["trace[1991836665] 'process raft request' (duration: 108.361672ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:25:03.665183Z","caller":"traceutil/trace.go:171","msg":"trace[1983844736] linearizableReadLoop","detail":"{readStateIndex:11877; appliedIndex:11876; }","duration":"180.860009ms","start":"2026-04-20T02:25:03.484299Z","end":"2026-04-20T02:25:03.665159Z","steps":["trace[1983844736] 'read index received' (duration: 180.617365ms)","trace[1983844736] 'applied index is now lower than readState.Index' (duration: 241.494µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:25:03.665391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.520736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:25:03.665435Z","caller":"traceutil/trace.go:171","msg":"trace[273468183] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:11352; }","duration":"142.637818ms","start":"2026-04-20T02:25:03.522785Z","end":"2026-04-20T02:25:03.665423Z","steps":["trace[273468183] 'agreement among raft nodes before linearized reading' (duration: 142.503186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:25:03.66552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.204875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-20T02:25:03.665614Z","caller":"traceutil/trace.go:171","msg":"trace[263809681] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11352; }","duration":"181.328108ms","start":"2026-04-20T02:25:03.484264Z","end":"2026-04-20T02:25:03.665592Z","steps":["trace[263809681] 'agreement among raft nodes before linearized reading' (duration: 181.026903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:25:03.666035Z","caller":"traceutil/trace.go:171","msg":"trace[225650055] transaction","detail":"{read_only:false; response_revision:11352; number_of_response:1; }","duration":"193.569778ms","start":"2026-04-20T02:25:03.471796Z","end":"2026-04-20T02:25:03.665366Z","steps":["trace[225650055] 'process raft request' (duration: 193.185121ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:26:25.830163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9524} {"level":"info","ts":"2026-04-20T02:26:25.929432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9524,"took":"96.822632ms","hash":1003064590,"current-db-size-bytes":61480960,"current-db-size":"62 MB","current-db-size-in-use-bytes":25276416,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T02:26:25.929501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1003064590,"revision":9524,"compact-revision":6635}