{"level":"warn","ts":"2026-02-26T21:01:38.589812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T21:01:38.589912Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-02-26T21:01:38.58999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T21:01:38.590015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-26T21:01:38.590035Z","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-02-26T21:01:38.590814Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-02-26T21:01:38.590959Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-02-26T21:01:38.594834Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.602268ms"} {"level":"info","ts":"2026-02-26T21:01:38.600978Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-02-26T21:01:38.601074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T21:01:38.6011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-02-26T21:01:38.601111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T21:01:38.601125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.601167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-02-26T21:01:38.607406Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T21:01:38.610245Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T21:01:38.611149Z","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-02-26T21:01:38.613481Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T21:01:38.613682Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T21:01:38.613726Z","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-02-26T21:01:38.613816Z","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-02-26T21:01:38.61383Z","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-02-26T21:01:38.61699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-02-26T21:01:38.617355Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-26T21:01:38.617549Z","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-02-26T21:01:38.617699Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-26T21:01:38.617745Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-26T21:01:38.617847Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T21:01:38.617897Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T21:01:39.301341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T21:01:39.30142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T21:01:39.301467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-02-26T21:01:39.301486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-02-26T21:01:39.301497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-26T21:01:39.301513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-02-26T21:01:39.301533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-26T21:01:39.302649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T21:01:39.302684Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:39.302752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:39.302773Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:39.302956Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T21:01:39.303008Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T21:01:39.303775Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:39.303936Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:39.304002Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:39.304549Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T21:01:39.305152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-02-26T21:01:56.392714Z","caller":"traceutil/trace.go:171","msg":"trace[684917564] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"192.503756ms","start":"2026-02-26T21:01:56.20018Z","end":"2026-02-26T21:01:56.392684Z","steps":["trace[684917564] 'process raft request' (duration: 192.32413ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:56.923369Z","caller":"traceutil/trace.go:171","msg":"trace[847115486] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"429.088572ms","start":"2026-02-26T21:01:56.494236Z","end":"2026-02-26T21:01:56.923325Z","steps":["trace[847115486] 'read index received' (duration: 427.643124ms)","trace[847115486] 'applied index is now lower than readState.Index' (duration: 1.444018ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:56.923507Z","caller":"traceutil/trace.go:171","msg":"trace[249510817] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"526.628865ms","start":"2026-02-26T21:01:56.396842Z","end":"2026-02-26T21:01:56.923471Z","steps":["trace[249510817] 'process raft request' (duration: 525.020952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.923658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.174432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:01:56.923673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.688185ms","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-02-26T21:01:56.92376Z","caller":"traceutil/trace.go:171","msg":"trace[1063613360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"306.337516ms","start":"2026-02-26T21:01:56.617399Z","end":"2026-02-26T21:01:56.923736Z","steps":["trace[1063613360] 'agreement among raft nodes before linearized reading' (duration: 306.170382ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:56.92381Z","caller":"traceutil/trace.go:171","msg":"trace[2044727683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"188.88725ms","start":"2026-02-26T21:01:56.734898Z","end":"2026-02-26T21:01:56.923785Z","steps":["trace[2044727683] 'agreement among raft nodes before linearized reading' (duration: 188.694545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.923732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.494232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-02-26T21:01:56.923825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.617371Z","time spent":"306.44264ms","remote":"127.0.0.1:37038","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-02-26T21:01:56.923868Z","caller":"traceutil/trace.go:171","msg":"trace[1852492820] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:404; }","duration":"429.689549ms","start":"2026-02-26T21:01:56.494163Z","end":"2026-02-26T21:01:56.923853Z","steps":["trace[1852492820] 'agreement among raft nodes before linearized reading' (duration: 429.358751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:56.923907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.494135Z","time spent":"429.761841ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6989,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-26T21:01:56.924297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.396805Z","time spent":"526.79746ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:09.765206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.601874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:09.765297Z","caller":"traceutil/trace.go:171","msg":"trace[1793028105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"149.752288ms","start":"2026-02-26T21:02:09.615524Z","end":"2026-02-26T21:02:09.765276Z","steps":["trace[1793028105] 'range keys from in-memory index tree' (duration: 149.517231ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:16.394966Z","caller":"traceutil/trace.go:171","msg":"trace[142674194] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"202.131127ms","start":"2026-02-26T21:02:16.1928Z","end":"2026-02-26T21:02:16.394931Z","steps":["trace[142674194] 'read index received' (duration: 201.709587ms)","trace[142674194] 'applied index is now lower than readState.Index' (duration: 420.41µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:16.395022Z","caller":"traceutil/trace.go:171","msg":"trace[1545413660] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"202.481457ms","start":"2026-02-26T21:02:16.192499Z","end":"2026-02-26T21:02:16.39498Z","steps":["trace[1545413660] 'process raft request' (duration: 202.179229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:16.395149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.313782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-cainjector:leaderelection\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:16.395207Z","caller":"traceutil/trace.go:171","msg":"trace[532058927] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-cainjector:leaderelection; range_end:; response_count:0; response_revision:511; }","duration":"202.421955ms","start":"2026-02-26T21:02:16.192764Z","end":"2026-02-26T21:02:16.395186Z","steps":["trace[532058927] 'agreement among raft nodes before linearized reading' (duration: 202.304483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:16.806895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.549294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T21:02:16.806907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.556315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:16.806983Z","caller":"traceutil/trace.go:171","msg":"trace[285972322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"180.686497ms","start":"2026-02-26T21:02:16.626278Z","end":"2026-02-26T21:02:16.806965Z","steps":["trace[285972322] 'range keys from in-memory index tree' (duration: 180.304537ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:16.806997Z","caller":"traceutil/trace.go:171","msg":"trace[1106500707] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:0; response_revision:511; }","duration":"387.678557ms","start":"2026-02-26T21:02:16.419298Z","end":"2026-02-26T21:02:16.806977Z","steps":["trace[1106500707] 'range keys from in-memory index tree' (duration: 387.493154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:16.80704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:16.41929Z","time spent":"387.743149ms","remote":"127.0.0.1:37526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/cert-manager-webhook\" "} {"level":"warn","ts":"2026-02-26T21:02:17.268465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.57859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:17.268565Z","caller":"traceutil/trace.go:171","msg":"trace[911479575] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"187.820256ms","start":"2026-02-26T21:02:17.080731Z","end":"2026-02-26T21:02:17.268552Z","steps":["trace[911479575] 'read index received' (duration: 49.898778ms)","trace[911479575] 'applied index is now lower than readState.Index' (duration: 137.920308ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:17.268629Z","caller":"traceutil/trace.go:171","msg":"trace[396676437] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"374.256138ms","start":"2026-02-26T21:02:16.894365Z","end":"2026-02-26T21:02:17.268621Z","steps":["trace[396676437] 'process raft request' (duration: 236.273278ms)","trace[396676437] 'compare' (duration: 137.346013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:17.268664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:16.894351Z","time spent":"374.297199ms","remote":"127.0.0.1:37126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":365,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:02:17.268839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.242339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-26T21:02:17.268867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.95273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T21:02:17.268929Z","caller":"traceutil/trace.go:171","msg":"trace[2070526788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"106.370702ms","start":"2026-02-26T21:02:17.162534Z","end":"2026-02-26T21:02:17.268905Z","steps":["trace[2070526788] 'agreement among raft nodes before linearized reading' (duration: 106.147266ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:17.268955Z","caller":"traceutil/trace.go:171","msg":"trace[370081818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"188.048273ms","start":"2026-02-26T21:02:17.080885Z","end":"2026-02-26T21:02:17.268933Z","steps":["trace[370081818] 'agreement among raft nodes before linearized reading' (duration: 187.786706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:17.269072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.34221ms","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-02-26T21:02:17.269134Z","caller":"traceutil/trace.go:171","msg":"trace[440868425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"188.435151ms","start":"2026-02-26T21:02:17.080685Z","end":"2026-02-26T21:02:17.26912Z","steps":["trace[440868425] 'agreement among raft nodes before linearized reading' (duration: 187.97582ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:17.387759Z","caller":"traceutil/trace.go:171","msg":"trace[1219769997] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"115.79641ms","start":"2026-02-26T21:02:17.271937Z","end":"2026-02-26T21:02:17.387734Z","steps":["trace[1219769997] 'process raft request' (duration: 103.290095ms)","trace[1219769997] 'compare' (duration: 12.155456ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:17.387833Z","caller":"traceutil/trace.go:171","msg":"trace[531195449] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"115.060322ms","start":"2026-02-26T21:02:17.272725Z","end":"2026-02-26T21:02:17.387785Z","steps":["trace[531195449] 'process raft request' (duration: 114.864637ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:17.387914Z","caller":"traceutil/trace.go:171","msg":"trace[525858564] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"114.800455ms","start":"2026-02-26T21:02:17.273087Z","end":"2026-02-26T21:02:17.387887Z","steps":["trace[525858564] 'process raft request' (duration: 114.651001ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:17.387837Z","caller":"traceutil/trace.go:171","msg":"trace[56579171] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"114.767394ms","start":"2026-02-26T21:02:17.273017Z","end":"2026-02-26T21:02:17.387784Z","steps":["trace[56579171] 'process raft request' (duration: 114.679592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:18.116682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846746022,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:18.380284Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004787961s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T21:02:18.617731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:18.31298Z","time spent":"304.740225ms","remote":"127.0.0.1:59964","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T21:02:18.618333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.576141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:18.618712Z","caller":"traceutil/trace.go:171","msg":"trace[930509954] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.268115013s","start":"2026-02-26T21:02:17.350482Z","end":"2026-02-26T21:02:18.618597Z","steps":["trace[930509954] 'process raft request' (duration: 1.030193682s)","trace[930509954] 'compare' (duration: 237.058088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:18.618888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:17.35046Z","time spent":"1.268344848s","remote":"127.0.0.1:37110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211512,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:02:18.632458Z","caller":"traceutil/trace.go:171","msg":"trace[2086338759] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"1.016508127s","start":"2026-02-26T21:02:17.615914Z","end":"2026-02-26T21:02:18.632422Z","steps":["trace[2086338759] 'read index received' (duration: 764.661446ms)","trace[2086338759] 'applied index is now lower than readState.Index' (duration: 251.844761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:18.632588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01665321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:18.632778Z","caller":"traceutil/trace.go:171","msg":"trace[1999129756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.016897477s","start":"2026-02-26T21:02:17.615864Z","end":"2026-02-26T21:02:18.632761Z","steps":["trace[1999129756] 'agreement among raft nodes before linearized reading' (duration: 1.01663625s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:18.632811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:17.615842Z","time spent":"1.016963548s","remote":"127.0.0.1:37038","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-02-26T21:02:19.454946Z","caller":"traceutil/trace.go:171","msg":"trace[2096087832] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"160.360124ms","start":"2026-02-26T21:02:19.29452Z","end":"2026-02-26T21:02:19.45488Z","steps":["trace[2096087832] 'process raft request' (duration: 160.178ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:32.908588Z","caller":"traceutil/trace.go:171","msg":"trace[401939203] linearizableReadLoop","detail":"{readStateIndex:1624; appliedIndex:1623; }","duration":"351.197653ms","start":"2026-02-26T21:03:32.557351Z","end":"2026-02-26T21:03:32.908549Z","steps":["trace[401939203] 'read index received' (duration: 342.621333ms)","trace[401939203] 'applied index is now lower than readState.Index' (duration: 8.57453ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:32.908608Z","caller":"traceutil/trace.go:171","msg":"trace[1965256999] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"422.021435ms","start":"2026-02-26T21:03:32.486541Z","end":"2026-02-26T21:03:32.908562Z","steps":["trace[1965256999] 'process raft request' (duration: 413.554097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:32.908881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:32.486519Z","time spent":"422.24088ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:32.908927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.543042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33be8dae-feed-4475-ae63-453f0e44ea59\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-02-26T21:03:32.90898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.581976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-02-26T21:03:32.908993Z","caller":"traceutil/trace.go:171","msg":"trace[1411893663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33be8dae-feed-4475-ae63-453f0e44ea59; range_end:; response_count:1; response_revision:1564; }","duration":"351.639834ms","start":"2026-02-26T21:03:32.557336Z","end":"2026-02-26T21:03:32.908976Z","steps":["trace[1411893663] 'agreement among raft nodes before linearized reading' (duration: 351.336937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:32.909045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:32.557279Z","time spent":"351.756688ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33be8dae-feed-4475-ae63-453f0e44ea59\" "} {"level":"info","ts":"2026-02-26T21:03:32.909035Z","caller":"traceutil/trace.go:171","msg":"trace[1750070328] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1564; }","duration":"334.691209ms","start":"2026-02-26T21:03:32.574327Z","end":"2026-02-26T21:03:32.909018Z","steps":["trace[1750070328] 'agreement among raft nodes before linearized reading' (duration: 334.446773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:32.90911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:32.574314Z","time spent":"334.781522ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-26T21:03:33.469352Z","caller":"traceutil/trace.go:171","msg":"trace[980834777] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"130.445026ms","start":"2026-02-26T21:03:33.338885Z","end":"2026-02-26T21:03:33.46933Z","steps":["trace[980834777] 'process raft request' (duration: 130.273602ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:33.580885Z","caller":"traceutil/trace.go:171","msg":"trace[1916059645] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"107.170166ms","start":"2026-02-26T21:03:33.473683Z","end":"2026-02-26T21:03:33.580853Z","steps":["trace[1916059645] 'process raft request' (duration: 106.8977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.133827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846748235,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:03:46.200563Z","caller":"traceutil/trace.go:171","msg":"trace[190865697] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"577.065293ms","start":"2026-02-26T21:03:45.623477Z","end":"2026-02-26T21:03:46.200542Z","steps":["trace[190865697] 'process raft request' (duration: 576.889468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.200691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.623454Z","time spent":"577.169295ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:46.298106Z","caller":"traceutil/trace.go:171","msg":"trace[123709106] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1723; }","duration":"664.787609ms","start":"2026-02-26T21:03:45.63329Z","end":"2026-02-26T21:03:46.298077Z","steps":["trace[123709106] 'read index received' (duration: 567.169989ms)","trace[123709106] 'applied index is now lower than readState.Index' (duration: 97.61661ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:46.298194Z","caller":"traceutil/trace.go:171","msg":"trace[1218067625] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"570.744941ms","start":"2026-02-26T21:03:45.72741Z","end":"2026-02-26T21:03:46.298155Z","steps":["trace[1218067625] 'process raft request' (duration: 570.546726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.29838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.032645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-02-26T21:03:46.298383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.727383Z","time spent":"570.907974ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:46.298411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.920167ms","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-02-26T21:03:46.298459Z","caller":"traceutil/trace.go:171","msg":"trace[1884284332] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1663; }","duration":"665.18298ms","start":"2026-02-26T21:03:45.633258Z","end":"2026-02-26T21:03:46.298441Z","steps":["trace[1884284332] 'agreement among raft nodes before linearized reading' (duration: 664.963453ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:46.298492Z","caller":"traceutil/trace.go:171","msg":"trace[1711281117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1663; }","duration":"269.051601ms","start":"2026-02-26T21:03:46.029419Z","end":"2026-02-26T21:03:46.29847Z","steps":["trace[1711281117] 'agreement among raft nodes before linearized reading' (duration: 268.873026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.298518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.764658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-02-26T21:03:46.298411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.083687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T21:03:46.298564Z","caller":"traceutil/trace.go:171","msg":"trace[608788875] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1663; }","duration":"295.83723ms","start":"2026-02-26T21:03:46.002706Z","end":"2026-02-26T21:03:46.298544Z","steps":["trace[608788875] 'agreement among raft nodes before linearized reading' (duration: 295.549082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.298582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.945426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-26T21:03:46.29858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.633243Z","time spent":"665.323643ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-02-26T21:03:46.298649Z","caller":"traceutil/trace.go:171","msg":"trace[692211120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1663; }","duration":"342.021357ms","start":"2026-02-26T21:03:45.956611Z","end":"2026-02-26T21:03:46.298632Z","steps":["trace[692211120] 'agreement among raft nodes before linearized reading' (duration: 341.73559ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:46.298639Z","caller":"traceutil/trace.go:171","msg":"trace[1325806769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1663; }","duration":"665.318872ms","start":"2026-02-26T21:03:45.633272Z","end":"2026-02-26T21:03:46.298591Z","steps":["trace[1325806769] 'agreement among raft nodes before linearized reading' (duration: 664.972813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.298693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.956546Z","time spent":"342.12871ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:03:46.298703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.421067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-26T21:03:46.298725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.633259Z","time spent":"665.448586ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:03:46.298772Z","caller":"traceutil/trace.go:171","msg":"trace[1358566326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1663; }","duration":"363.5364ms","start":"2026-02-26T21:03:45.935216Z","end":"2026-02-26T21:03:46.298752Z","steps":["trace[1358566326] 'agreement among raft nodes before linearized reading' (duration: 363.198382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:46.298813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:45.9352Z","time spent":"363.602351ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:03:49.959387Z","caller":"traceutil/trace.go:171","msg":"trace[1984546454] linearizableReadLoop","detail":"{readStateIndex:1754; appliedIndex:1753; }","duration":"381.708118ms","start":"2026-02-26T21:03:49.57766Z","end":"2026-02-26T21:03:49.959368Z","steps":["trace[1984546454] 'read index received' (duration: 381.498223ms)","trace[1984546454] 'applied index is now lower than readState.Index' (duration: 209.135µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:49.959491Z","caller":"traceutil/trace.go:171","msg":"trace[226230764] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"386.701276ms","start":"2026-02-26T21:03:49.572765Z","end":"2026-02-26T21:03:49.959467Z","steps":["trace[226230764] 'process raft request' (duration: 386.4569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:49.959546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.990157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:49.959564Z","caller":"traceutil/trace.go:171","msg":"trace[85457627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1691; }","duration":"344.022439ms","start":"2026-02-26T21:03:49.615536Z","end":"2026-02-26T21:03:49.959558Z","steps":["trace[85457627] 'agreement among raft nodes before linearized reading' (duration: 343.998398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:49.959584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:49.615528Z","time spent":"344.051639ms","remote":"127.0.0.1:37038","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-02-26T21:03:49.959513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.842151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:03:49.959674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:49.572756Z","time spent":"386.807709ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:49.959688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.058713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:49.95971Z","caller":"traceutil/trace.go:171","msg":"trace[1899292054] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1691; }","duration":"382.075067ms","start":"2026-02-26T21:03:49.577618Z","end":"2026-02-26T21:03:49.959693Z","steps":["trace[1899292054] 'agreement among raft nodes before linearized reading' (duration: 381.847881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:49.959694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.623376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:49.959853Z","caller":"traceutil/trace.go:171","msg":"trace[481207517] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1691; }","duration":"145.833112ms","start":"2026-02-26T21:03:49.813993Z","end":"2026-02-26T21:03:49.959826Z","steps":["trace[481207517] 'agreement among raft nodes before linearized reading' (duration: 145.580535ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:49.959752Z","caller":"traceutil/trace.go:171","msg":"trace[761175402] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1691; }","duration":"280.165456ms","start":"2026-02-26T21:03:49.679573Z","end":"2026-02-26T21:03:49.959739Z","steps":["trace[761175402] 'agreement among raft nodes before linearized reading' (duration: 280.024853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:49.959785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:49.577602Z","time spent":"382.144898ms","remote":"127.0.0.1:59176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:04:22.52081Z","caller":"traceutil/trace.go:171","msg":"trace[1223096873] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"102.75426ms","start":"2026-02-26T21:04:22.418031Z","end":"2026-02-26T21:04:22.520785Z","steps":["trace[1223096873] 'process raft request' (duration: 102.515505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:22.79479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.163115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:04:22.795106Z","caller":"traceutil/trace.go:171","msg":"trace[6922359] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"269.813105ms","start":"2026-02-26T21:04:22.525269Z","end":"2026-02-26T21:04:22.795082Z","steps":["trace[6922359] 'process raft request' (duration: 269.64133ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:22.795118Z","caller":"traceutil/trace.go:171","msg":"trace[1653014544] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1926; }","duration":"250.564237ms","start":"2026-02-26T21:04:22.54449Z","end":"2026-02-26T21:04:22.795054Z","steps":["trace[1653014544] 'read index received' (duration: 20.799515ms)","trace[1653014544] 'applied index is now lower than readState.Index' (duration: 229.763432ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:22.795179Z","caller":"traceutil/trace.go:171","msg":"trace[969706862] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"270.02167ms","start":"2026-02-26T21:04:22.525119Z","end":"2026-02-26T21:04:22.795141Z","steps":["trace[969706862] 'process raft request' (duration: 59.411204ms)","trace[969706862] 'compare' (duration: 210.039872ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:04:22.795328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.805877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:04:22.795382Z","caller":"traceutil/trace.go:171","msg":"trace[612326182] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1860; }","duration":"232.892518ms","start":"2026-02-26T21:04:22.562476Z","end":"2026-02-26T21:04:22.795369Z","steps":["trace[612326182] 'agreement among raft nodes before linearized reading' (duration: 232.809106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:22.795401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.386811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:04:22.795377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.857474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-02-26T21:04:22.79545Z","caller":"traceutil/trace.go:171","msg":"trace[2081540552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"179.479763ms","start":"2026-02-26T21:04:22.615954Z","end":"2026-02-26T21:04:22.795434Z","steps":["trace[2081540552] 'agreement among raft nodes before linearized reading' (duration: 179.401031ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:22.795524Z","caller":"traceutil/trace.go:171","msg":"trace[534604992] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"251.020607ms","start":"2026-02-26T21:04:22.544435Z","end":"2026-02-26T21:04:22.795456Z","steps":["trace[534604992] 'agreement among raft nodes before linearized reading' (duration: 250.726861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:22.795796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.67482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-26T21:04:22.795937Z","caller":"traceutil/trace.go:171","msg":"trace[339021209] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1860; }","duration":"202.793032ms","start":"2026-02-26T21:04:22.593062Z","end":"2026-02-26T21:04:22.795855Z","steps":["trace[339021209] 'agreement among raft nodes before linearized reading' (duration: 202.470943ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:35.707924Z","caller":"traceutil/trace.go:171","msg":"trace[488705718] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"138.659277ms","start":"2026-02-26T21:05:35.569231Z","end":"2026-02-26T21:05:35.70789Z","steps":["trace[488705718] 'process raft request' (duration: 138.340498ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:16.051045Z","caller":"traceutil/trace.go:171","msg":"trace[691254135] transaction","detail":"{read_only:false; response_revision:4125; number_of_response:1; }","duration":"147.16306ms","start":"2026-02-26T21:09:15.903842Z","end":"2026-02-26T21:09:16.051005Z","steps":["trace[691254135] 'process raft request' (duration: 146.972135ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:16.058405Z","caller":"traceutil/trace.go:171","msg":"trace[465242820] transaction","detail":"{read_only:false; response_revision:4126; number_of_response:1; }","duration":"104.161363ms","start":"2026-02-26T21:09:15.954146Z","end":"2026-02-26T21:09:16.058307Z","steps":["trace[465242820] 'process raft request' (duration: 103.724861ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.495108Z","caller":"traceutil/trace.go:171","msg":"trace[1491047308] transaction","detail":"{read_only:false; response_revision:4167; number_of_response:1; }","duration":"233.094523ms","start":"2026-02-26T21:09:17.26198Z","end":"2026-02-26T21:09:17.495074Z","steps":["trace[1491047308] 'process raft request' (duration: 223.224449ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.53733Z","caller":"traceutil/trace.go:171","msg":"trace[971393652] linearizableReadLoop","detail":"{readStateIndex:4332; appliedIndex:4330; }","duration":"241.952991ms","start":"2026-02-26T21:09:17.29535Z","end":"2026-02-26T21:09:17.537303Z","steps":["trace[971393652] 'read index received' (duration: 189.8849ms)","trace[971393652] 'applied index is now lower than readState.Index' (duration: 52.067041ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:17.537406Z","caller":"traceutil/trace.go:171","msg":"trace[159919509] transaction","detail":"{read_only:false; response_revision:4168; number_of_response:1; }","duration":"267.538719ms","start":"2026-02-26T21:09:17.269845Z","end":"2026-02-26T21:09:17.537384Z","steps":["trace[159919509] 'process raft request' (duration: 267.153329ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.53764Z","caller":"traceutil/trace.go:171","msg":"trace[24500462] transaction","detail":"{read_only:false; response_revision:4170; number_of_response:1; }","duration":"200.228826ms","start":"2026-02-26T21:09:17.337401Z","end":"2026-02-26T21:09:17.53763Z","steps":["trace[24500462] 'process raft request' (duration: 199.784594ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.537453Z","caller":"traceutil/trace.go:171","msg":"trace[665239220] transaction","detail":"{read_only:false; response_revision:4169; number_of_response:1; }","duration":"200.041251ms","start":"2026-02-26T21:09:17.3374Z","end":"2026-02-26T21:09:17.537441Z","steps":["trace[665239220] 'process raft request' (duration: 199.715322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:17.537968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.607957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:2948"} {"level":"info","ts":"2026-02-26T21:09:17.538038Z","caller":"traceutil/trace.go:171","msg":"trace[1914490613] transaction","detail":"{read_only:false; response_revision:4171; number_of_response:1; }","duration":"192.073467ms","start":"2026-02-26T21:09:17.345908Z","end":"2026-02-26T21:09:17.537982Z","steps":["trace[1914490613] 'process raft request' (duration: 191.330486ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.53807Z","caller":"traceutil/trace.go:171","msg":"trace[1937101085] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4171; }","duration":"242.740571ms","start":"2026-02-26T21:09:17.2953Z","end":"2026-02-26T21:09:17.538041Z","steps":["trace[1937101085] 'agreement among raft nodes before linearized reading' (duration: 242.494034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:17.539575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.10622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-02-26T21:09:17.539678Z","caller":"traceutil/trace.go:171","msg":"trace[536306783] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4172; }","duration":"215.259464ms","start":"2026-02-26T21:09:17.32438Z","end":"2026-02-26T21:09:17.539639Z","steps":["trace[536306783] 'agreement among raft nodes before linearized reading' (duration: 215.001518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:17.539683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.431538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-02-26T21:09:17.539688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.798341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-02-26T21:09:17.539689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.54899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-26T21:09:17.539812Z","caller":"traceutil/trace.go:171","msg":"trace[1218889216] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4172; }","duration":"147.730044ms","start":"2026-02-26T21:09:17.392053Z","end":"2026-02-26T21:09:17.539783Z","steps":["trace[1218889216] 'agreement among raft nodes before linearized reading' (duration: 147.392476ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.539742Z","caller":"traceutil/trace.go:171","msg":"trace[1169000814] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4172; }","duration":"208.54305ms","start":"2026-02-26T21:09:17.33118Z","end":"2026-02-26T21:09:17.539723Z","steps":["trace[1169000814] 'agreement among raft nodes before linearized reading' (duration: 208.418197ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:17.539743Z","caller":"traceutil/trace.go:171","msg":"trace[223593729] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:4172; }","duration":"179.888022ms","start":"2026-02-26T21:09:17.359842Z","end":"2026-02-26T21:09:17.53973Z","steps":["trace[223593729] 'agreement among raft nodes before linearized reading' (duration: 179.684897ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:25.479096Z","caller":"traceutil/trace.go:171","msg":"trace[660555348] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"155.695049ms","start":"2026-02-26T21:09:25.323378Z","end":"2026-02-26T21:09:25.479073Z","steps":["trace[660555348] 'process raft request' (duration: 155.573386ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:25.770933Z","caller":"traceutil/trace.go:171","msg":"trace[1046465459] linearizableReadLoop","detail":"{readStateIndex:4640; appliedIndex:4639; }","duration":"270.68824ms","start":"2026-02-26T21:09:25.500211Z","end":"2026-02-26T21:09:25.770899Z","steps":["trace[1046465459] 'read index received' (duration: 270.563887ms)","trace[1046465459] 'applied index is now lower than readState.Index' (duration: 123.223µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:25.771055Z","caller":"traceutil/trace.go:171","msg":"trace[1870139694] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"444.134496ms","start":"2026-02-26T21:09:25.326883Z","end":"2026-02-26T21:09:25.771018Z","steps":["trace[1870139694] 'process raft request' (duration: 443.821948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:25.771131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.891076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:25.771179Z","caller":"traceutil/trace.go:171","msg":"trace[556207118] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4468; }","duration":"271.028221ms","start":"2026-02-26T21:09:25.500136Z","end":"2026-02-26T21:09:25.771164Z","steps":["trace[556207118] 'agreement among raft nodes before linearized reading' (duration: 270.915407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:25.771262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:25.326857Z","time spent":"444.284951ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3703,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:09:25.776718Z","caller":"traceutil/trace.go:171","msg":"trace[1824274167] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"210.174203ms","start":"2026-02-26T21:09:25.566523Z","end":"2026-02-26T21:09:25.776698Z","steps":["trace[1824274167] 'process raft request' (duration: 210.005658ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:25.776904Z","caller":"traceutil/trace.go:171","msg":"trace[447176898] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"133.830647ms","start":"2026-02-26T21:09:25.643049Z","end":"2026-02-26T21:09:25.77688Z","steps":["trace[447176898] 'process raft request' (duration: 133.61771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:25.776997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.410417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:09:25.777023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.858772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:25.777104Z","caller":"traceutil/trace.go:171","msg":"trace[1073200871] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4470; }","duration":"121.598701ms","start":"2026-02-26T21:09:25.655481Z","end":"2026-02-26T21:09:25.777079Z","steps":["trace[1073200871] 'agreement among raft nodes before linearized reading' (duration: 121.396417ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:25.777112Z","caller":"traceutil/trace.go:171","msg":"trace[1090271418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4470; }","duration":"161.000756ms","start":"2026-02-26T21:09:25.616092Z","end":"2026-02-26T21:09:25.777092Z","steps":["trace[1090271418] 'agreement among raft nodes before linearized reading' (duration: 160.831972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:25.777116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.783513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T21:09:25.777154Z","caller":"traceutil/trace.go:171","msg":"trace[807220101] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:4470; }","duration":"202.880075ms","start":"2026-02-26T21:09:25.574265Z","end":"2026-02-26T21:09:25.777145Z","steps":["trace[807220101] 'agreement among raft nodes before linearized reading' (duration: 202.715131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:25.777397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.57518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718804"} {"level":"info","ts":"2026-02-26T21:09:25.777459Z","caller":"traceutil/trace.go:171","msg":"trace[302581027] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4470; }","duration":"258.642721ms","start":"2026-02-26T21:09:25.5188Z","end":"2026-02-26T21:09:25.777443Z","steps":["trace[302581027] 'agreement among raft nodes before linearized reading' (duration: 258.031595ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:29.576832Z","caller":"traceutil/trace.go:171","msg":"trace[1304595714] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"209.617398ms","start":"2026-02-26T21:09:29.367182Z","end":"2026-02-26T21:09:29.5768Z","steps":["trace[1304595714] 'process raft request' (duration: 209.412582ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:29.583636Z","caller":"traceutil/trace.go:171","msg":"trace[931614869] linearizableReadLoop","detail":"{readStateIndex:4724; appliedIndex:4722; }","duration":"143.913035ms","start":"2026-02-26T21:09:29.439706Z","end":"2026-02-26T21:09:29.583619Z","steps":["trace[931614869] 'read index received' (duration: 136.953596ms)","trace[931614869] 'applied index is now lower than readState.Index' (duration: 6.958669ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:29.583688Z","caller":"traceutil/trace.go:171","msg":"trace[174664892] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"211.619839ms","start":"2026-02-26T21:09:29.372052Z","end":"2026-02-26T21:09:29.583672Z","steps":["trace[174664892] 'process raft request' (duration: 211.341662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:29.584016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.281716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-02-26T21:09:29.584156Z","caller":"traceutil/trace.go:171","msg":"trace[2039122483] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4549; }","duration":"144.429829ms","start":"2026-02-26T21:09:29.439689Z","end":"2026-02-26T21:09:29.584119Z","steps":["trace[2039122483] 'agreement among raft nodes before linearized reading' (duration: 144.022278ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:37.011834Z","caller":"traceutil/trace.go:171","msg":"trace[585270763] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"104.792639ms","start":"2026-02-26T21:09:36.907008Z","end":"2026-02-26T21:09:37.011801Z","steps":["trace[585270763] 'process raft request' (duration: 86.738404ms)","trace[585270763] 'compare' (duration: 17.861569ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:37.011988Z","caller":"traceutil/trace.go:171","msg":"trace[1776819699] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"104.922562ms","start":"2026-02-26T21:09:36.907027Z","end":"2026-02-26T21:09:37.01195Z","steps":["trace[1776819699] 'process raft request' (duration: 104.733776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.447783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846755928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:09:46.517383Z","caller":"traceutil/trace.go:171","msg":"trace[21327693] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"577.577452ms","start":"2026-02-26T21:09:45.93977Z","end":"2026-02-26T21:09:46.517347Z","steps":["trace[21327693] 'process raft request' (duration: 577.368147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.517686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:45.939734Z","time spent":"577.758846ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:09:46.764233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.91223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:09:46.764373Z","caller":"traceutil/trace.go:171","msg":"trace[806013333] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4998; }","duration":"817.470475ms","start":"2026-02-26T21:09:45.946884Z","end":"2026-02-26T21:09:46.764355Z","steps":["trace[806013333] 'read index received' (duration: 570.304745ms)","trace[806013333] 'applied index is now lower than readState.Index' (duration: 247.16411ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:09:46.76446Z","caller":"traceutil/trace.go:171","msg":"trace[520872113] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"823.723958ms","start":"2026-02-26T21:09:45.940727Z","end":"2026-02-26T21:09:46.764451Z","steps":["trace[520872113] 'process raft request' (duration: 627.4871ms)","trace[520872113] 'compare' (duration: 195.797576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:46.764521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:45.940689Z","time spent":"823.799489ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:09:46.764745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.856604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T21:09:46.764776Z","caller":"traceutil/trace.go:171","msg":"trace[1135961049] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:4819; }","duration":"817.918675ms","start":"2026-02-26T21:09:45.94685Z","end":"2026-02-26T21:09:46.764769Z","steps":["trace[1135961049] 'agreement among raft nodes before linearized reading' (duration: 817.785022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.764801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:45.946839Z","time spent":"817.955776ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-7rqbs\" "} {"level":"warn","ts":"2026-02-26T21:09:46.76483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.691669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-26T21:09:46.764851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.638119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:09:46.764942Z","caller":"traceutil/trace.go:171","msg":"trace[1141583043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4819; }","duration":"794.815362ms","start":"2026-02-26T21:09:45.970106Z","end":"2026-02-26T21:09:46.764921Z","steps":["trace[1141583043] 'agreement among raft nodes before linearized reading' (duration: 794.630357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.764974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.954676ms","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-02-26T21:09:46.764991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:45.970075Z","time spent":"794.906995ms","remote":"127.0.0.1:53796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:09:46.764951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.779456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:46.765014Z","caller":"traceutil/trace.go:171","msg":"trace[1604687386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4819; }","duration":"345.039578ms","start":"2026-02-26T21:09:46.419964Z","end":"2026-02-26T21:09:46.765004Z","steps":["trace[1604687386] 'agreement among raft nodes before linearized reading' (duration: 344.941936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.41995Z","time spent":"345.091249ms","remote":"127.0.0.1:37296","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":"info","ts":"2026-02-26T21:09:46.765061Z","caller":"traceutil/trace.go:171","msg":"trace[1449276090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4819; }","duration":"147.95893ms","start":"2026-02-26T21:09:46.61708Z","end":"2026-02-26T21:09:46.765039Z","steps":["trace[1449276090] 'agreement among raft nodes before linearized reading' (duration: 147.742205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.76504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.676988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T21:09:46.765102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.68275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T21:09:46.764885Z","caller":"traceutil/trace.go:171","msg":"trace[1535090199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4819; }","duration":"348.794921ms","start":"2026-02-26T21:09:46.416076Z","end":"2026-02-26T21:09:46.764871Z","steps":["trace[1535090199] 'agreement among raft nodes before linearized reading' (duration: 348.656637ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:46.765167Z","caller":"traceutil/trace.go:171","msg":"trace[568825217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4819; }","duration":"546.873172ms","start":"2026-02-26T21:09:46.218262Z","end":"2026-02-26T21:09:46.765136Z","steps":["trace[568825217] 'agreement among raft nodes before linearized reading' (duration: 546.515713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.550131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-02-26T21:09:46.765213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.416049Z","time spent":"349.154679ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T21:09:46.765224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.201634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-26T21:09:46.765238Z","caller":"traceutil/trace.go:171","msg":"trace[1387086031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4819; }","duration":"345.610252ms","start":"2026-02-26T21:09:46.419619Z","end":"2026-02-26T21:09:46.765229Z","steps":["trace[1387086031] 'agreement among raft nodes before linearized reading' (duration: 345.51556ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:46.765262Z","caller":"traceutil/trace.go:171","msg":"trace[883044799] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4; range_end:; response_count:1; response_revision:4819; }","duration":"757.288747ms","start":"2026-02-26T21:09:46.007961Z","end":"2026-02-26T21:09:46.76525Z","steps":["trace[883044799] 'agreement among raft nodes before linearized reading' (duration: 757.195595ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:46.765162Z","caller":"traceutil/trace.go:171","msg":"trace[115970670] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4819; }","duration":"335.756473ms","start":"2026-02-26T21:09:46.42939Z","end":"2026-02-26T21:09:46.765147Z","steps":["trace[115970670] 'agreement among raft nodes before linearized reading' (duration: 335.416865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.419609Z","time spent":"345.653463ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T21:09:46.765296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.00793Z","time spent":"757.358449ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4\" "} {"level":"warn","ts":"2026-02-26T21:09:46.765307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.429383Z","time spent":"335.912506ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T21:09:46.765417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.894912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:09:46.765433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.398848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:09:46.765457Z","caller":"traceutil/trace.go:171","msg":"trace[1026994935] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4819; }","duration":"421.990665ms","start":"2026-02-26T21:09:46.343456Z","end":"2026-02-26T21:09:46.765446Z","steps":["trace[1026994935] 'agreement among raft nodes before linearized reading' (duration: 421.912804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.812908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-26T21:09:46.765476Z","caller":"traceutil/trace.go:171","msg":"trace[1982811979] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4819; }","duration":"700.47421ms","start":"2026-02-26T21:09:46.064987Z","end":"2026-02-26T21:09:46.765461Z","steps":["trace[1982811979] 'agreement among raft nodes before linearized reading' (duration: 700.353247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.343426Z","time spent":"422.056886ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:09:46.765508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.064969Z","time spent":"700.532912ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:09:46.765505Z","caller":"traceutil/trace.go:171","msg":"trace[129304906] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4; range_end:; response_count:1; response_revision:4819; }","duration":"244.201187ms","start":"2026-02-26T21:09:46.521289Z","end":"2026-02-26T21:09:46.76549Z","steps":["trace[129304906] 'agreement among raft nodes before linearized reading' (duration: 243.684916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.036051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:09:46.765588Z","caller":"traceutil/trace.go:171","msg":"trace[687373763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4819; }","duration":"357.163055ms","start":"2026-02-26T21:09:46.408398Z","end":"2026-02-26T21:09:46.765561Z","steps":["trace[687373763] 'agreement among raft nodes before linearized reading' (duration: 356.917588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:46.765642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.408373Z","time spent":"357.256527ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:09:46.765246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.218228Z","time spent":"547.001855ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:09:47.894955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065065166s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:09:47.895712Z","caller":"traceutil/trace.go:171","msg":"trace[1902093647] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"1.125772466s","start":"2026-02-26T21:09:46.7699Z","end":"2026-02-26T21:09:47.895672Z","steps":["trace[1902093647] 'process raft request' (duration: 1.125456498s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:47.895754Z","caller":"traceutil/trace.go:171","msg":"trace[635042910] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"1.125215543s","start":"2026-02-26T21:09:46.770499Z","end":"2026-02-26T21:09:47.895715Z","steps":["trace[635042910] 'process raft request' (duration: 1.125041099s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:47.895808Z","caller":"traceutil/trace.go:171","msg":"trace[1248653293] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"1.125261163s","start":"2026-02-26T21:09:46.770504Z","end":"2026-02-26T21:09:47.895765Z","steps":["trace[1248653293] 'process raft request' (duration: 1.125081329s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:47.895875Z","caller":"traceutil/trace.go:171","msg":"trace[360111951] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"1.124008314s","start":"2026-02-26T21:09:46.771785Z","end":"2026-02-26T21:09:47.895794Z","steps":["trace[360111951] 'process raft request' (duration: 1.123869281s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:47.8959Z","caller":"traceutil/trace.go:171","msg":"trace[609377169] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"1.124151578s","start":"2026-02-26T21:09:46.771724Z","end":"2026-02-26T21:09:47.895876Z","steps":["trace[609377169] 'process raft request' (duration: 1.123895262s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:47.895917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.76989Z","time spent":"1.12591315s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:09:47.895935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.770473Z","time spent":"1.125341615s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:09:47.895945Z","caller":"traceutil/trace.go:171","msg":"trace[1131776678] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"1.1218421s","start":"2026-02-26T21:09:46.774085Z","end":"2026-02-26T21:09:47.895927Z","steps":["trace[1131776678] 'process raft request' (duration: 1.121615345s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:47.895995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.771712Z","time spent":"1.12424263s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:09:47.896019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.77048Z","time spent":"1.125428847s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:09:47.895812Z","caller":"traceutil/trace.go:171","msg":"trace[93172589] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:5000; }","duration":"1.125422568s","start":"2026-02-26T21:09:46.770339Z","end":"2026-02-26T21:09:47.895761Z","steps":["trace[93172589] 'read index received' (duration: 58.431646ms)","trace[93172589] 'applied index is now lower than readState.Index' (duration: 1.066989902s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:09:47.896078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.774071Z","time spent":"1.121918852s","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:09:47.896083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.77178Z","time spent":"1.124180608s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:09:47.89612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125768776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80099"} {"level":"info","ts":"2026-02-26T21:09:47.896164Z","caller":"traceutil/trace.go:171","msg":"trace[1603480969] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:4826; }","duration":"1.125843279s","start":"2026-02-26T21:09:46.770309Z","end":"2026-02-26T21:09:47.896153Z","steps":["trace[1603480969] 'agreement among raft nodes before linearized reading' (duration: 1.125577402s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:47.8962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:46.770301Z","time spent":"1.12589072s","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80123,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-02-26T21:09:47.941104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.293361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:09:47.941191Z","caller":"traceutil/trace.go:171","msg":"trace[1324102165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4826; }","duration":"285.447374ms","start":"2026-02-26T21:09:47.655724Z","end":"2026-02-26T21:09:47.941171Z","steps":["trace[1324102165] 'agreement among raft nodes before linearized reading' (duration: 285.293631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:47.941209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.348507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-02-26T21:09:47.941101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.617114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-26T21:09:47.94143Z","caller":"traceutil/trace.go:171","msg":"trace[2092505548] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26182ee9-5802-461e-bd0c-0a01b145bee4; range_end:; response_count:1; response_revision:4826; }","duration":"170.617773ms","start":"2026-02-26T21:09:47.770762Z","end":"2026-02-26T21:09:47.94138Z","steps":["trace[2092505548] 'agreement among raft nodes before linearized reading' (duration: 170.195523ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:09:47.94141Z","caller":"traceutil/trace.go:171","msg":"trace[704836260] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4826; }","duration":"377.967602ms","start":"2026-02-26T21:09:47.56342Z","end":"2026-02-26T21:09:47.941387Z","steps":["trace[704836260] 'agreement among raft nodes before linearized reading' (duration: 377.501361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:09:47.941509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:09:47.563398Z","time spent":"378.094475ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-02-26T21:09:58.705016Z","caller":"traceutil/trace.go:171","msg":"trace[904061379] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"134.03708ms","start":"2026-02-26T21:09:58.57095Z","end":"2026-02-26T21:09:58.704987Z","steps":["trace[904061379] 'process raft request' (duration: 133.796374ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.874749Z","caller":"traceutil/trace.go:171","msg":"trace[62997172] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5217; }","duration":"164.064024ms","start":"2026-02-26T21:10:02.710665Z","end":"2026-02-26T21:10:02.874729Z","steps":["trace[62997172] 'read index received' (duration: 163.976071ms)","trace[62997172] 'applied index is now lower than readState.Index' (duration: 87.023µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:02.874801Z","caller":"traceutil/trace.go:171","msg":"trace[499963186] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"172.52278ms","start":"2026-02-26T21:10:02.702249Z","end":"2026-02-26T21:10:02.874772Z","steps":["trace[499963186] 'process raft request' (duration: 172.346345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:02.874959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.270688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-02-26T21:10:02.874993Z","caller":"traceutil/trace.go:171","msg":"trace[1289266014] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5030; }","duration":"164.33878ms","start":"2026-02-26T21:10:02.710644Z","end":"2026-02-26T21:10:02.874983Z","steps":["trace[1289266014] 'agreement among raft nodes before linearized reading' (duration: 164.185336ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:02.882271Z","caller":"traceutil/trace.go:171","msg":"trace[432622949] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"156.692692ms","start":"2026-02-26T21:10:02.725557Z","end":"2026-02-26T21:10:02.882249Z","steps":["trace[432622949] 'process raft request' (duration: 156.560999ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:03.085234Z","caller":"traceutil/trace.go:171","msg":"trace[46101941] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"129.507709ms","start":"2026-02-26T21:10:02.955698Z","end":"2026-02-26T21:10:03.085206Z","steps":["trace[46101941] 'process raft request' (duration: 97.393876ms)","trace[46101941] 'compare' (duration: 31.998301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:10:46.549575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.597855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-26T21:10:46.549678Z","caller":"traceutil/trace.go:171","msg":"trace[1720042840] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5377; }","duration":"138.737879ms","start":"2026-02-26T21:10:46.410918Z","end":"2026-02-26T21:10:46.549656Z","steps":["trace[1720042840] 'range keys from in-memory index tree' (duration: 138.402001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:46.550184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.264169ms","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-02-26T21:10:46.550289Z","caller":"traceutil/trace.go:171","msg":"trace[1733537768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5377; }","duration":"135.414803ms","start":"2026-02-26T21:10:46.414854Z","end":"2026-02-26T21:10:46.550269Z","steps":["trace[1733537768] 'range keys from in-memory index tree' (duration: 135.089695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:46.550208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.926679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:10:46.550393Z","caller":"traceutil/trace.go:171","msg":"trace[1443086833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5377; }","duration":"133.144295ms","start":"2026-02-26T21:10:46.417224Z","end":"2026-02-26T21:10:46.550368Z","steps":["trace[1443086833] 'range keys from in-memory index tree' (duration: 132.835967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:46.550574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.579222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T21:10:46.550694Z","caller":"traceutil/trace.go:171","msg":"trace[734068056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5377; }","duration":"134.729126ms","start":"2026-02-26T21:10:46.415955Z","end":"2026-02-26T21:10:46.550684Z","steps":["trace[734068056] 'range keys from in-memory index tree' (duration: 133.502264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:46.813766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.831658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-02-26T21:10:46.813837Z","caller":"traceutil/trace.go:171","msg":"trace[1547750516] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5381; }","duration":"162.917151ms","start":"2026-02-26T21:10:46.650901Z","end":"2026-02-26T21:10:46.813818Z","steps":["trace[1547750516] 'range keys from in-memory index tree' (duration: 162.691155ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:46.985829Z","caller":"traceutil/trace.go:171","msg":"trace[2046391785] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"144.316542ms","start":"2026-02-26T21:10:46.841469Z","end":"2026-02-26T21:10:46.985785Z","steps":["trace[2046391785] 'process raft request' (duration: 144.035616ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:47.276056Z","caller":"traceutil/trace.go:171","msg":"trace[673691280] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"113.248564ms","start":"2026-02-26T21:10:47.162769Z","end":"2026-02-26T21:10:47.276018Z","steps":["trace[673691280] 'process raft request' (duration: 58.202667ms)","trace[673691280] 'compare' (duration: 54.924373ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:50.754967Z","caller":"traceutil/trace.go:171","msg":"trace[343821753] linearizableReadLoop","detail":"{readStateIndex:5610; appliedIndex:5608; }","duration":"108.587206ms","start":"2026-02-26T21:10:50.646356Z","end":"2026-02-26T21:10:50.754943Z","steps":["trace[343821753] 'read index received' (duration: 4.473344ms)","trace[343821753] 'applied index is now lower than readState.Index' (duration: 104.112932ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:50.755453Z","caller":"traceutil/trace.go:171","msg":"trace[1754185477] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"121.650161ms","start":"2026-02-26T21:10:50.633787Z","end":"2026-02-26T21:10:50.755437Z","steps":["trace[1754185477] 'process raft request' (duration: 121.010385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:10:50.755542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.138311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:10:50.755673Z","caller":"traceutil/trace.go:171","msg":"trace[871445181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5411; }","duration":"109.342995ms","start":"2026-02-26T21:10:50.646305Z","end":"2026-02-26T21:10:50.755648Z","steps":["trace[871445181] 'agreement among raft nodes before linearized reading' (duration: 109.067108ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:50.830244Z","caller":"traceutil/trace.go:171","msg":"trace[1230495540] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"142.544345ms","start":"2026-02-26T21:10:50.687652Z","end":"2026-02-26T21:10:50.830196Z","steps":["trace[1230495540] 'process raft request' (duration: 105.605611ms)","trace[1230495540] 'compare' (duration: 36.729408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:10:50.830389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.618995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T21:10:50.830469Z","caller":"traceutil/trace.go:171","msg":"trace[214263629] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5412; }","duration":"120.768079ms","start":"2026-02-26T21:10:50.709683Z","end":"2026-02-26T21:10:50.830451Z","steps":["trace[214263629] 'agreement among raft nodes before linearized reading' (duration: 120.500961ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:58.878836Z","caller":"traceutil/trace.go:171","msg":"trace[926586333] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"107.894939ms","start":"2026-02-26T21:10:58.770896Z","end":"2026-02-26T21:10:58.878791Z","steps":["trace[926586333] 'process raft request' (duration: 54.36897ms)","trace[926586333] 'compare' (duration: 53.359095ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:59.026415Z","caller":"traceutil/trace.go:171","msg":"trace[953285813] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"122.781341ms","start":"2026-02-26T21:10:58.903602Z","end":"2026-02-26T21:10:59.026383Z","steps":["trace[953285813] 'process raft request' (duration: 103.674731ms)","trace[953285813] 'compare' (duration: 18.935675ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:59.20064Z","caller":"traceutil/trace.go:171","msg":"trace[1079984733] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"106.760679ms","start":"2026-02-26T21:10:59.093848Z","end":"2026-02-26T21:10:59.200608Z","steps":["trace[1079984733] 'process raft request' (duration: 85.427854ms)","trace[1079984733] 'compare' (duration: 21.196922ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:10:59.25867Z","caller":"traceutil/trace.go:171","msg":"trace[1308211330] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"121.479326ms","start":"2026-02-26T21:10:59.137156Z","end":"2026-02-26T21:10:59.258635Z","steps":["trace[1308211330] 'process raft request' (duration: 121.280261ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:10:59.259118Z","caller":"traceutil/trace.go:171","msg":"trace[551931693] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"101.162058ms","start":"2026-02-26T21:10:59.157911Z","end":"2026-02-26T21:10:59.259073Z","steps":["trace[551931693] 'process raft request' (duration: 100.669025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:11:06.524324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.859559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-02-26T21:11:06.524392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.052166ms","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-02-26T21:11:06.524474Z","caller":"traceutil/trace.go:171","msg":"trace[30402727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5533; }","duration":"103.19189ms","start":"2026-02-26T21:11:06.421264Z","end":"2026-02-26T21:11:06.524456Z","steps":["trace[30402727] 'range keys from in-memory index tree' (duration: 102.8464ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:11:06.524411Z","caller":"traceutil/trace.go:171","msg":"trace[104771581] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:5533; }","duration":"101.004593ms","start":"2026-02-26T21:11:06.423387Z","end":"2026-02-26T21:11:06.524392Z","steps":["trace[104771581] 'range keys from in-memory index tree' (duration: 100.599343ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:11:39.340573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2725} {"level":"info","ts":"2026-02-26T21:11:39.396317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2725,"took":"54.377271ms","hash":575539951,"current-db-size-bytes":42082304,"current-db-size":"42 MB","current-db-size-in-use-bytes":31531008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-02-26T21:11:39.396369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575539951,"revision":2725,"compact-revision":-1} {"level":"warn","ts":"2026-02-26T21:15:11.105289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846762266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:15:11.605719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846762266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:15:11.667918Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063171874s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:15:11.668362Z","caller":"traceutil/trace.go:171","msg":"trace[612304304] transaction","detail":"{read_only:false; response_revision:7124; number_of_response:1; }","duration":"1.063693637s","start":"2026-02-26T21:15:10.604645Z","end":"2026-02-26T21:15:11.668338Z","steps":["trace[612304304] 'process raft request' (duration: 1.063509993s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:11.668498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.604634Z","time spent":"1.063785979s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:12.61587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0002811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-26T21:15:12.615982Z","caller":"traceutil/trace.go:171","msg":"trace[1379248231] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000433404s","start":"2026-02-26T21:15:10.615528Z","end":"2026-02-26T21:15:12.615961Z","steps":["trace[1379248231] 'agreement among raft nodes before linearized reading' (duration: 2.000306721s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:12.616028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.615516Z","time spent":"2.000502915s","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 21:15:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-26T21:15:13.258764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.590625202s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:15:13.259228Z","caller":"traceutil/trace.go:171","msg":"trace[58304472] transaction","detail":"{read_only:false; response_revision:7126; number_of_response:1; }","duration":"2.651928508s","start":"2026-02-26T21:15:10.607278Z","end":"2026-02-26T21:15:13.259206Z","steps":["trace[58304472] 'process raft request' (duration: 2.651836025s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.259302Z","caller":"traceutil/trace.go:171","msg":"trace[865350119] transaction","detail":"{read_only:false; response_revision:7125; number_of_response:1; }","duration":"2.654575056s","start":"2026-02-26T21:15:10.604708Z","end":"2026-02-26T21:15:13.259283Z","steps":["trace[865350119] 'process raft request' (duration: 2.654275408s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.259362Z","caller":"traceutil/trace.go:171","msg":"trace[801901801] transaction","detail":"{read_only:false; response_revision:7127; number_of_response:1; }","duration":"2.64922487s","start":"2026-02-26T21:15:10.610129Z","end":"2026-02-26T21:15:13.259354Z","steps":["trace[801901801] 'process raft request' (duration: 2.649019784s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.259389Z","caller":"traceutil/trace.go:171","msg":"trace[1157434981] transaction","detail":"{read_only:false; response_revision:7129; number_of_response:1; }","duration":"2.649189119s","start":"2026-02-26T21:15:10.61019Z","end":"2026-02-26T21:15:13.259379Z","steps":["trace[1157434981] 'process raft request' (duration: 2.649019714s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.259407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.610121Z","time spent":"2.649261081s","remote":"127.0.0.1:59178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2245,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:15:13.25942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.604685Z","time spent":"2.654671579s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:15:13.259461Z","caller":"traceutil/trace.go:171","msg":"trace[1322539693] transaction","detail":"{read_only:false; response_revision:7128; number_of_response:1; }","duration":"2.649259s","start":"2026-02-26T21:15:10.610196Z","end":"2026-02-26T21:15:13.259455Z","steps":["trace[1322539693] 'process raft request' (duration: 2.648985934s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.259502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.610188Z","time spent":"2.649292111s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:13.259324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.607268Z","time spent":"2.65199238s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:13.259436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.610164Z","time spent":"2.64925248s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:15:13.25967Z","caller":"traceutil/trace.go:171","msg":"trace[1378960469] transaction","detail":"{read_only:false; response_revision:7130; number_of_response:1; }","duration":"2.649417414s","start":"2026-02-26T21:15:10.610246Z","end":"2026-02-26T21:15:13.259663Z","steps":["trace[1378960469] 'process raft request' (duration: 2.648990454s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.259716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.610227Z","time spent":"2.649466335s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:15:13.259858Z","caller":"traceutil/trace.go:171","msg":"trace[1584993353] linearizableReadLoop","detail":"{readStateIndex:7390; appliedIndex:7382; }","duration":"2.655019077s","start":"2026-02-26T21:15:10.604829Z","end":"2026-02-26T21:15:13.259848Z","steps":["trace[1584993353] 'read index received' (duration: 1.063332628s)","trace[1584993353] 'applied index is now lower than readState.Index' (duration: 1.591685609s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:15:13.260007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655173632s","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-02-26T21:15:13.260003Z","caller":"traceutil/trace.go:171","msg":"trace[603009997] transaction","detail":"{read_only:false; response_revision:7131; number_of_response:1; }","duration":"2.086731538s","start":"2026-02-26T21:15:11.173255Z","end":"2026-02-26T21:15:13.259987Z","steps":["trace[603009997] 'process raft request' (duration: 2.086356888s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.260038Z","caller":"traceutil/trace.go:171","msg":"trace[1448994496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7131; }","duration":"2.655216292s","start":"2026-02-26T21:15:10.604814Z","end":"2026-02-26T21:15:13.260031Z","steps":["trace[1448994496] 'agreement among raft nodes before linearized reading' (duration: 2.65510658s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.604808Z","time spent":"2.655245444s","remote":"127.0.0.1:37296","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-02-26T21:15:13.260074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.17323Z","time spent":"2.086811481s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:15:13.260181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.834812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:13.260211Z","caller":"traceutil/trace.go:171","msg":"trace[1004003608] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:7131; }","duration":"681.898693ms","start":"2026-02-26T21:15:12.578302Z","end":"2026-02-26T21:15:13.260201Z","steps":["trace[1004003608] 'agreement among raft nodes before linearized reading' (duration: 681.854372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:12.578285Z","time spent":"681.948534ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.260311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655409188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12811"} {"level":"info","ts":"2026-02-26T21:15:13.260385Z","caller":"traceutil/trace.go:171","msg":"trace[1115526948] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7131; }","duration":"2.65550309s","start":"2026-02-26T21:15:10.604867Z","end":"2026-02-26T21:15:13.26037Z","steps":["trace[1115526948] 'agreement among raft nodes before linearized reading' (duration: 2.655292324s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.152416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"warn","ts":"2026-02-26T21:15:13.260418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.604861Z","time spent":"2.655550521s","remote":"127.0.0.1:37266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":12835,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"info","ts":"2026-02-26T21:15:13.26043Z","caller":"traceutil/trace.go:171","msg":"trace[1945071026] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7131; }","duration":"222.215178ms","start":"2026-02-26T21:15:13.038208Z","end":"2026-02-26T21:15:13.260423Z","steps":["trace[1945071026] 'agreement among raft nodes before linearized reading' (duration: 222.142356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.420878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:15:13.260469Z","caller":"traceutil/trace.go:171","msg":"trace[201033372] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:7131; }","duration":"830.4766ms","start":"2026-02-26T21:15:12.429984Z","end":"2026-02-26T21:15:13.26046Z","steps":["trace[201033372] 'agreement among raft nodes before linearized reading' (duration: 830.438599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:12.429966Z","time spent":"830.51834ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.260406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.643167715s","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-02-26T21:15:13.260521Z","caller":"traceutil/trace.go:171","msg":"trace[960632444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7131; }","duration":"2.643294848s","start":"2026-02-26T21:15:10.61722Z","end":"2026-02-26T21:15:13.260515Z","steps":["trace[960632444] 'agreement among raft nodes before linearized reading' (duration: 2.643134114s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.617214Z","time spent":"2.643326779s","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T21:15:13.260513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.303152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:15:13.260603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.650591416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T21:15:13.260644Z","caller":"traceutil/trace.go:171","msg":"trace[1464943451] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7131; }","duration":"2.650651197s","start":"2026-02-26T21:15:10.609981Z","end":"2026-02-26T21:15:13.260632Z","steps":["trace[1464943451] 'agreement among raft nodes before linearized reading' (duration: 2.650545723s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.260639Z","caller":"traceutil/trace.go:171","msg":"trace[1370408283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7131; }","duration":"644.432627ms","start":"2026-02-26T21:15:12.616155Z","end":"2026-02-26T21:15:13.260588Z","steps":["trace[1370408283] 'agreement among raft nodes before linearized reading' (duration: 644.275252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.260665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720946992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:15:13.260721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.184575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T21:15:13.260724Z","caller":"traceutil/trace.go:171","msg":"trace[785212732] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:7131; }","duration":"1.721014205s","start":"2026-02-26T21:15:11.539698Z","end":"2026-02-26T21:15:13.260713Z","steps":["trace[785212732] 'agreement among raft nodes before linearized reading' (duration: 1.720880942s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:15:13.26075Z","caller":"traceutil/trace.go:171","msg":"trace[1545440231] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:7131; }","duration":"863.249357ms","start":"2026-02-26T21:15:12.397493Z","end":"2026-02-26T21:15:13.260743Z","steps":["trace[1545440231] 'agreement among raft nodes before linearized reading' (duration: 863.182185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.26077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:11.539642Z","time spent":"1.721117567s","remote":"127.0.0.1:37188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":7,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:15:13.260782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:12.397475Z","time spent":"863.302148ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-02-26T21:15:13.260811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.609972Z","time spent":"2.650696528s","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-26T21:15:13.260647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655706546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:1 size:7403"} {"level":"warn","ts":"2026-02-26T21:15:13.260985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64652831s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223559"} {"level":"info","ts":"2026-02-26T21:15:13.261015Z","caller":"traceutil/trace.go:171","msg":"trace[1064016031] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7131; }","duration":"2.646583372s","start":"2026-02-26T21:15:10.614426Z","end":"2026-02-26T21:15:13.261009Z","steps":["trace[1064016031] 'agreement among raft nodes before linearized reading' (duration: 2.646283055s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.261038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.614414Z","time spent":"2.646618753s","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":223583,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-02-26T21:15:13.260703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:12.616139Z","time spent":"644.541529ms","remote":"127.0.0.1:37040","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-02-26T21:15:13.260995Z","caller":"traceutil/trace.go:171","msg":"trace[635753087] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:1; response_revision:7131; }","duration":"2.656012753s","start":"2026-02-26T21:15:10.604911Z","end":"2026-02-26T21:15:13.260923Z","steps":["trace[635753087] 'agreement among raft nodes before linearized reading' (duration: 2.655670435s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:13.261694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:15:10.604902Z","time spent":"2.656769052s","remote":"127.0.0.1:37266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":7427,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"info","ts":"2026-02-26T21:15:50.625043Z","caller":"traceutil/trace.go:171","msg":"trace[474847522] linearizableReadLoop","detail":"{readStateIndex:7766; appliedIndex:7765; }","duration":"101.095132ms","start":"2026-02-26T21:15:50.523928Z","end":"2026-02-26T21:15:50.625023Z","steps":["trace[474847522] 'read index received' (duration: 100.956389ms)","trace[474847522] 'applied index is now lower than readState.Index' (duration: 138.053µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:15:50.625108Z","caller":"traceutil/trace.go:171","msg":"trace[1650328373] transaction","detail":"{read_only:false; response_revision:7497; number_of_response:1; }","duration":"147.51305ms","start":"2026-02-26T21:15:50.477564Z","end":"2026-02-26T21:15:50.625077Z","steps":["trace[1650328373] 'process raft request' (duration: 147.333155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:15:50.625175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.242976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:15:50.6252Z","caller":"traceutil/trace.go:171","msg":"trace[1093884305] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:7497; }","duration":"101.345418ms","start":"2026-02-26T21:15:50.523849Z","end":"2026-02-26T21:15:50.625195Z","steps":["trace[1093884305] 'agreement among raft nodes before linearized reading' (duration: 101.272607ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:01.982048Z","caller":"traceutil/trace.go:171","msg":"trace[1591196406] transaction","detail":"{read_only:false; response_revision:7551; number_of_response:1; }","duration":"135.613436ms","start":"2026-02-26T21:16:01.846399Z","end":"2026-02-26T21:16:01.982012Z","steps":["trace[1591196406] 'process raft request' (duration: 135.35831ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:03.619128Z","caller":"traceutil/trace.go:171","msg":"trace[108308287] transaction","detail":"{read_only:false; response_revision:7555; number_of_response:1; }","duration":"132.883845ms","start":"2026-02-26T21:16:03.486187Z","end":"2026-02-26T21:16:03.619071Z","steps":["trace[108308287] 'process raft request' (duration: 116.433354ms)","trace[108308287] 'compare' (duration: 16.094132ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:03.786424Z","caller":"traceutil/trace.go:171","msg":"trace[4746306] transaction","detail":"{read_only:false; response_revision:7558; number_of_response:1; }","duration":"163.298633ms","start":"2026-02-26T21:16:03.623087Z","end":"2026-02-26T21:16:03.786386Z","steps":["trace[4746306] 'process raft request' (duration: 98.63857ms)","trace[4746306] 'compare' (duration: 64.476548ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:03.786697Z","caller":"traceutil/trace.go:171","msg":"trace[277410826] transaction","detail":"{read_only:false; response_revision:7559; number_of_response:1; }","duration":"162.327638ms","start":"2026-02-26T21:16:03.624348Z","end":"2026-02-26T21:16:03.786675Z","steps":["trace[277410826] 'process raft request' (duration: 161.997139ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:03.786677Z","caller":"traceutil/trace.go:171","msg":"trace[2078950283] linearizableReadLoop","detail":"{readStateIndex:7832; appliedIndex:7829; }","duration":"159.797163ms","start":"2026-02-26T21:16:03.626795Z","end":"2026-02-26T21:16:03.786593Z","steps":["trace[2078950283] 'read index received' (duration: 59.600323ms)","trace[2078950283] 'applied index is now lower than readState.Index' (duration: 100.19093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:03.787028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.477928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-26T21:16:03.787044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.213883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T21:16:03.787078Z","caller":"traceutil/trace.go:171","msg":"trace[1953183052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7559; }","duration":"156.566611ms","start":"2026-02-26T21:16:03.630498Z","end":"2026-02-26T21:16:03.787065Z","steps":["trace[1953183052] 'agreement among raft nodes before linearized reading' (duration: 156.416066ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:03.7871Z","caller":"traceutil/trace.go:171","msg":"trace[1916843502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7559; }","duration":"160.314626ms","start":"2026-02-26T21:16:03.626768Z","end":"2026-02-26T21:16:03.787082Z","steps":["trace[1916843502] 'agreement among raft nodes before linearized reading' (duration: 160.11987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:03.787315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.404147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T21:16:03.787396Z","caller":"traceutil/trace.go:171","msg":"trace[1890269986] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:7559; }","duration":"156.607742ms","start":"2026-02-26T21:16:03.630771Z","end":"2026-02-26T21:16:03.787379Z","steps":["trace[1890269986] 'agreement among raft nodes before linearized reading' (duration: 156.327925ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:03.967426Z","caller":"traceutil/trace.go:171","msg":"trace[1297396804] transaction","detail":"{read_only:false; response_revision:7560; number_of_response:1; }","duration":"176.414468ms","start":"2026-02-26T21:16:03.790979Z","end":"2026-02-26T21:16:03.967393Z","steps":["trace[1297396804] 'process raft request' (duration: 98.460136ms)","trace[1297396804] 'compare' (duration: 77.579713ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:03.967434Z","caller":"traceutil/trace.go:171","msg":"trace[1327269515] linearizableReadLoop","detail":"{readStateIndex:7834; appliedIndex:7832; }","duration":"176.081479ms","start":"2026-02-26T21:16:03.791317Z","end":"2026-02-26T21:16:03.967398Z","steps":["trace[1327269515] 'read index received' (duration: 98.128087ms)","trace[1327269515] 'applied index is now lower than readState.Index' (duration: 77.952202ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:03.967894Z","caller":"traceutil/trace.go:171","msg":"trace[139116487] transaction","detail":"{read_only:false; response_revision:7561; number_of_response:1; }","duration":"176.700306ms","start":"2026-02-26T21:16:03.791165Z","end":"2026-02-26T21:16:03.967865Z","steps":["trace[139116487] 'process raft request' (duration: 176.132301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:03.96791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.585611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223811"} {"level":"info","ts":"2026-02-26T21:16:03.967971Z","caller":"traceutil/trace.go:171","msg":"trace[1061274982] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7561; }","duration":"176.687774ms","start":"2026-02-26T21:16:03.791271Z","end":"2026-02-26T21:16:03.967959Z","steps":["trace[1061274982] 'agreement among raft nodes before linearized reading' (duration: 176.217012ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:04.237798Z","caller":"traceutil/trace.go:171","msg":"trace[1085973431] transaction","detail":"{read_only:false; response_revision:7563; number_of_response:1; }","duration":"192.656633ms","start":"2026-02-26T21:16:04.045117Z","end":"2026-02-26T21:16:04.237773Z","steps":["trace[1085973431] 'process raft request' (duration: 110.433752ms)","trace[1085973431] 'compare' (duration: 81.820201ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:31.467738Z","caller":"traceutil/trace.go:171","msg":"trace[698913452] transaction","detail":"{read_only:false; response_revision:7744; number_of_response:1; }","duration":"107.344213ms","start":"2026-02-26T21:16:31.360371Z","end":"2026-02-26T21:16:31.467715Z","steps":["trace[698913452] 'process raft request' (duration: 107.052776ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:39.347093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5769} {"level":"info","ts":"2026-02-26T21:16:39.441167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5769,"took":"92.037201ms","hash":132960503,"current-db-size-bytes":43917312,"current-db-size":"44 MB","current-db-size-in-use-bytes":24035328,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T21:16:39.44124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":132960503,"revision":5769,"compact-revision":2725} {"level":"info","ts":"2026-02-26T21:16:43.743038Z","caller":"traceutil/trace.go:171","msg":"trace[314063174] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7842; }","duration":"307.98541ms","start":"2026-02-26T21:16:43.435004Z","end":"2026-02-26T21:16:43.74299Z","steps":["trace[314063174] 'process raft request' (duration: 261.614434ms)","trace[314063174] 'compare' (duration: 46.243003ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:43.743178Z","caller":"traceutil/trace.go:171","msg":"trace[1750090435] linearizableReadLoop","detail":"{readStateIndex:8128; appliedIndex:8126; }","duration":"266.071098ms","start":"2026-02-26T21:16:43.477073Z","end":"2026-02-26T21:16:43.743144Z","steps":["trace[1750090435] 'read index received' (duration: 13.806093ms)","trace[1750090435] 'applied index is now lower than readState.Index' (duration: 252.263855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:43.743325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:43.434994Z","time spent":"308.160084ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-02-26T21:16:43.743445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.360035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hdwzc\" ","response":"range_response_count:1 size:4665"} {"level":"info","ts":"2026-02-26T21:16:43.743494Z","caller":"traceutil/trace.go:171","msg":"trace[1868946794] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hdwzc; range_end:; response_count:1; response_revision:7842; }","duration":"266.463897ms","start":"2026-02-26T21:16:43.477017Z","end":"2026-02-26T21:16:43.743481Z","steps":["trace[1868946794] 'agreement among raft nodes before linearized reading' (duration: 266.295533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:43.743593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.591377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hdwzc\" ","response":"range_response_count:1 size:4665"} {"level":"warn","ts":"2026-02-26T21:16:43.743606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.036642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:16:43.74366Z","caller":"traceutil/trace.go:171","msg":"trace[1720648812] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hdwzc; range_end:; response_count:1; response_revision:7842; }","duration":"247.672458ms","start":"2026-02-26T21:16:43.495969Z","end":"2026-02-26T21:16:43.743641Z","steps":["trace[1720648812] 'agreement among raft nodes before linearized reading' (duration: 247.480663ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:43.743703Z","caller":"traceutil/trace.go:171","msg":"trace[287812263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7842; }","duration":"128.192615ms","start":"2026-02-26T21:16:43.615485Z","end":"2026-02-26T21:16:43.743677Z","steps":["trace[287812263] 'agreement among raft nodes before linearized reading' (duration: 128.00329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:43.74364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.93351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-hdwzc\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-02-26T21:16:43.743766Z","caller":"traceutil/trace.go:171","msg":"trace[725333709] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-hdwzc; range_end:; response_count:1; response_revision:7842; }","duration":"247.099084ms","start":"2026-02-26T21:16:43.496653Z","end":"2026-02-26T21:16:43.743752Z","steps":["trace[725333709] 'agreement among raft nodes before linearized reading' (duration: 246.875288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:44.39599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.406008ms","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":"warn","ts":"2026-02-26T21:16:44.396041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.310394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T21:16:44.396073Z","caller":"traceutil/trace.go:171","msg":"trace[42243969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7849; }","duration":"176.547432ms","start":"2026-02-26T21:16:44.219507Z","end":"2026-02-26T21:16:44.396054Z","steps":["trace[42243969] 'range keys from in-memory index tree' (duration: 176.180391ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:44.396132Z","caller":"traceutil/trace.go:171","msg":"trace[355609314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7849; }","duration":"221.47526ms","start":"2026-02-26T21:16:44.17463Z","end":"2026-02-26T21:16:44.396105Z","steps":["trace[355609314] 'range keys from in-memory index tree' (duration: 221.045667ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:44.396222Z","caller":"traceutil/trace.go:171","msg":"trace[885111904] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"176.551631ms","start":"2026-02-26T21:16:44.219642Z","end":"2026-02-26T21:16:44.396194Z","steps":["trace[885111904] 'process raft request' (duration: 176.426968ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:44.396211Z","caller":"traceutil/trace.go:171","msg":"trace[164416473] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"176.554411ms","start":"2026-02-26T21:16:44.219608Z","end":"2026-02-26T21:16:44.396162Z","steps":["trace[164416473] 'process raft request' (duration: 87.190607ms)","trace[164416473] 'compare' (duration: 89.003574ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:44.800142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.544628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:16:44.800501Z","caller":"traceutil/trace.go:171","msg":"trace[2005116110] transaction","detail":"{read_only:false; response_revision:7853; number_of_response:1; }","duration":"399.508527ms","start":"2026-02-26T21:16:44.40092Z","end":"2026-02-26T21:16:44.800429Z","steps":["trace[2005116110] 'process raft request' (duration: 255.558459ms)","trace[2005116110] 'compare' (duration: 143.330302ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:16:44.800682Z","caller":"traceutil/trace.go:171","msg":"trace[606923011] transaction","detail":"{read_only:false; response_revision:7857; number_of_response:1; }","duration":"315.947712ms","start":"2026-02-26T21:16:44.484709Z","end":"2026-02-26T21:16:44.800656Z","steps":["trace[606923011] 'process raft request' (duration: 315.878941ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:44.800729Z","caller":"traceutil/trace.go:171","msg":"trace[538926179] transaction","detail":"{read_only:false; response_revision:7854; number_of_response:1; }","duration":"399.532009ms","start":"2026-02-26T21:16:44.401176Z","end":"2026-02-26T21:16:44.800708Z","steps":["trace[538926179] 'process raft request' (duration: 399.128978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:44.800794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:44.484698Z","time spent":"316.052356ms","remote":"127.0.0.1:37090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:16:44.800787Z","caller":"traceutil/trace.go:171","msg":"trace[611491658] linearizableReadLoop","detail":"{readStateIndex:8143; appliedIndex:8139; }","duration":"316.863197ms","start":"2026-02-26T21:16:44.483897Z","end":"2026-02-26T21:16:44.80076Z","steps":["trace[611491658] 'read index received' (duration: 172.56106ms)","trace[611491658] 'applied index is now lower than readState.Index' (duration: 144.299807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:44.800843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:44.401159Z","time spent":"399.622941ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:16:44.800836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:44.400894Z","time spent":"399.705093ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:16:44.80084Z","caller":"traceutil/trace.go:171","msg":"trace[195019269] transaction","detail":"{read_only:false; response_revision:7856; number_of_response:1; }","duration":"374.91928ms","start":"2026-02-26T21:16:44.425891Z","end":"2026-02-26T21:16:44.800811Z","steps":["trace[195019269] 'process raft request' (duration: 374.629172ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:44.800899Z","caller":"traceutil/trace.go:171","msg":"trace[528622940] transaction","detail":"{read_only:false; response_revision:7855; number_of_response:1; }","duration":"391.076243ms","start":"2026-02-26T21:16:44.409786Z","end":"2026-02-26T21:16:44.800862Z","steps":["trace[528622940] 'process raft request' (duration: 390.654512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:44.800951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:44.425878Z","time spent":"375.018842ms","remote":"127.0.0.1:37266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:16:44.801003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.837082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:16:44.801024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.116893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hdwzc\" ","response":"range_response_count:1 size:4650"} {"level":"warn","ts":"2026-02-26T21:16:44.801038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:44.409757Z","time spent":"391.203476ms","remote":"127.0.0.1:51324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:16:44.801065Z","caller":"traceutil/trace.go:171","msg":"trace[1442895973] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hdwzc; range_end:; response_count:1; response_revision:7857; }","duration":"317.196575ms","start":"2026-02-26T21:16:44.483854Z","end":"2026-02-26T21:16:44.801051Z","steps":["trace[1442895973] 'agreement among raft nodes before linearized reading' (duration: 317.039041ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:44.801065Z","caller":"traceutil/trace.go:171","msg":"trace[696142962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7857; }","duration":"184.937365ms","start":"2026-02-26T21:16:44.61611Z","end":"2026-02-26T21:16:44.801047Z","steps":["trace[696142962] 'agreement among raft nodes before linearized reading' (duration: 184.842382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:16:44.801118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:16:44.483837Z","time spent":"317.270506ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4674,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hdwzc\" "} {"level":"info","ts":"2026-02-26T21:16:45.928049Z","caller":"traceutil/trace.go:171","msg":"trace[1130935392] transaction","detail":"{read_only:false; response_revision:7861; number_of_response:1; }","duration":"121.081814ms","start":"2026-02-26T21:16:45.806936Z","end":"2026-02-26T21:16:45.928017Z","steps":["trace[1130935392] 'process raft request' (duration: 120.386237ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:16:57.928351Z","caller":"traceutil/trace.go:171","msg":"trace[1610713732] linearizableReadLoop","detail":"{readStateIndex:8249; appliedIndex:8248; }","duration":"102.13629ms","start":"2026-02-26T21:16:57.826195Z","end":"2026-02-26T21:16:57.928332Z","steps":["trace[1610713732] 'read index received' (duration: 100.486047ms)","trace[1610713732] 'applied index is now lower than readState.Index' (duration: 1.649243ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:16:57.928657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.436968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:16:57.928754Z","caller":"traceutil/trace.go:171","msg":"trace[494342053] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7956; }","duration":"102.602451ms","start":"2026-02-26T21:16:57.826133Z","end":"2026-02-26T21:16:57.928736Z","steps":["trace[494342053] 'agreement among raft nodes before linearized reading' (duration: 102.262313ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:17:43.256539Z","caller":"traceutil/trace.go:171","msg":"trace[900187585] linearizableReadLoop","detail":"{readStateIndex:8752; appliedIndex:8751; }","duration":"148.362252ms","start":"2026-02-26T21:17:43.108154Z","end":"2026-02-26T21:17:43.256517Z","steps":["trace[900187585] 'read index received' (duration: 50.656778ms)","trace[900187585] 'applied index is now lower than readState.Index' (duration: 97.704324ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:17:43.256626Z","caller":"traceutil/trace.go:171","msg":"trace[1924768702] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"158.497462ms","start":"2026-02-26T21:17:43.098094Z","end":"2026-02-26T21:17:43.256592Z","steps":["trace[1924768702] 'process raft request' (duration: 60.719126ms)","trace[1924768702] 'compare' (duration: 97.379425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:17:43.256905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.955434ms","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-02-26T21:17:43.256938Z","caller":"traceutil/trace.go:171","msg":"trace[1897438914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8448; }","duration":"145.041716ms","start":"2026-02-26T21:17:43.111889Z","end":"2026-02-26T21:17:43.25693Z","steps":["trace[1897438914] 'agreement among raft nodes before linearized reading' (duration: 144.753359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:17:43.257141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.965886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-02-26T21:17:43.257232Z","caller":"traceutil/trace.go:171","msg":"trace[374810645] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:8448; }","duration":"149.101971ms","start":"2026-02-26T21:17:43.108105Z","end":"2026-02-26T21:17:43.257207Z","steps":["trace[374810645] 'agreement among raft nodes before linearized reading' (duration: 148.510195ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:17:43.485326Z","caller":"traceutil/trace.go:171","msg":"trace[173709816] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"223.9456ms","start":"2026-02-26T21:17:43.261327Z","end":"2026-02-26T21:17:43.485273Z","steps":["trace[173709816] 'process raft request' (duration: 194.095875ms)","trace[173709816] 'compare' (duration: 29.6436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:17:43.841828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.353505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:17:43.841943Z","caller":"traceutil/trace.go:171","msg":"trace[1039762233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8449; }","duration":"225.523239ms","start":"2026-02-26T21:17:43.616402Z","end":"2026-02-26T21:17:43.841925Z","steps":["trace[1039762233] 'range keys from in-memory index tree' (duration: 225.184631ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:17:44.20771Z","caller":"traceutil/trace.go:171","msg":"trace[1311463777] linearizableReadLoop","detail":"{readStateIndex:8755; appliedIndex:8754; }","duration":"127.507678ms","start":"2026-02-26T21:17:44.080172Z","end":"2026-02-26T21:17:44.20768Z","steps":["trace[1311463777] 'read index received' (duration: 126.574254ms)","trace[1311463777] 'applied index is now lower than readState.Index' (duration: 932.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:17:44.2078Z","caller":"traceutil/trace.go:171","msg":"trace[1168054196] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"193.238202ms","start":"2026-02-26T21:17:44.014527Z","end":"2026-02-26T21:17:44.207765Z","steps":["trace[1168054196] 'process raft request' (duration: 192.205746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:17:44.207949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.748634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:17:44.208Z","caller":"traceutil/trace.go:171","msg":"trace[140733602] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8451; }","duration":"127.887558ms","start":"2026-02-26T21:17:44.080097Z","end":"2026-02-26T21:17:44.207985Z","steps":["trace[140733602] 'agreement among raft nodes before linearized reading' (duration: 127.763345ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:17:44.227376Z","caller":"traceutil/trace.go:171","msg":"trace[20936846] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"102.464326ms","start":"2026-02-26T21:17:44.124887Z","end":"2026-02-26T21:17:44.227351Z","steps":["trace[20936846] 'process raft request' (duration: 102.261221ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:17:44.361055Z","caller":"traceutil/trace.go:171","msg":"trace[91668444] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"122.889999ms","start":"2026-02-26T21:17:44.238116Z","end":"2026-02-26T21:17:44.361006Z","steps":["trace[91668444] 'process raft request' (duration: 122.583672ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:20:06.626574Z","caller":"traceutil/trace.go:171","msg":"trace[1414432476] transaction","detail":"{read_only:false; response_revision:9472; number_of_response:1; }","duration":"129.660114ms","start":"2026-02-26T21:20:06.496884Z","end":"2026-02-26T21:20:06.626544Z","steps":["trace[1414432476] 'process raft request' (duration: 128.957476ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:20:26.083276Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T21:20:26.090863Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T21:20:26.090973Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T21:21:25.213218Z","caller":"traceutil/trace.go:171","msg":"trace[894692332] linearizableReadLoop","detail":"{readStateIndex:10558; appliedIndex:10557; }","duration":"171.514896ms","start":"2026-02-26T21:21:25.04168Z","end":"2026-02-26T21:21:25.213195Z","steps":["trace[894692332] 'read index received' (duration: 171.321662ms)","trace[894692332] 'applied index is now lower than readState.Index' (duration: 192.374µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:21:25.213472Z","caller":"traceutil/trace.go:171","msg":"trace[1628683383] transaction","detail":"{read_only:false; response_revision:10201; number_of_response:1; }","duration":"190.838511ms","start":"2026-02-26T21:21:25.022622Z","end":"2026-02-26T21:21:25.21346Z","steps":["trace[1628683383] 'process raft request' (duration: 190.447941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:25.213722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.03401ms","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-02-26T21:21:25.213763Z","caller":"traceutil/trace.go:171","msg":"trace[1257365528] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:10201; }","duration":"172.131812ms","start":"2026-02-26T21:21:25.041621Z","end":"2026-02-26T21:21:25.213753Z","steps":["trace[1257365528] 'agreement among raft nodes before linearized reading' (duration: 172.04164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:25.751056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.35779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-02-26T21:21:25.751125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.436469ms","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-02-26T21:21:25.751156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.491724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:21:25.751166Z","caller":"traceutil/trace.go:171","msg":"trace[2024291834] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10201; }","duration":"435.530884ms","start":"2026-02-26T21:21:25.315617Z","end":"2026-02-26T21:21:25.751148Z","steps":["trace[2024291834] 'range keys from in-memory index tree' (duration: 435.114004ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:25.75124Z","caller":"traceutil/trace.go:171","msg":"trace[1666420345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10201; }","duration":"256.621527ms","start":"2026-02-26T21:21:25.494604Z","end":"2026-02-26T21:21:25.751226Z","steps":["trace[1666420345] 'range keys from in-memory index tree' (duration: 256.32456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:25.75127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:25.315599Z","time spent":"435.658088ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-26T21:21:25.75143Z","caller":"traceutil/trace.go:171","msg":"trace[1848241502] linearizableReadLoop","detail":"{readStateIndex:10559; appliedIndex:10558; }","duration":"134.916629ms","start":"2026-02-26T21:21:25.616502Z","end":"2026-02-26T21:21:25.751419Z","steps":["trace[1848241502] 'read index received' (duration: 122.606472ms)","trace[1848241502] 'applied index is now lower than readState.Index' (duration: 12.308927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:21:25.751453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.907383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-02-26T21:21:25.751506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.008721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:25.75154Z","caller":"traceutil/trace.go:171","msg":"trace[1093962534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10202; }","duration":"135.081962ms","start":"2026-02-26T21:21:25.616448Z","end":"2026-02-26T21:21:25.75153Z","steps":["trace[1093962534] 'agreement among raft nodes before linearized reading' (duration: 135.023071ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:25.751563Z","caller":"traceutil/trace.go:171","msg":"trace[828306461] transaction","detail":"{read_only:false; response_revision:10202; number_of_response:1; }","duration":"245.144384ms","start":"2026-02-26T21:21:25.506405Z","end":"2026-02-26T21:21:25.751549Z","steps":["trace[828306461] 'process raft request' (duration: 232.700804ms)","trace[828306461] 'compare' (duration: 11.67383ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:21:25.751639Z","caller":"traceutil/trace.go:171","msg":"trace[1721765044] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10201; }","duration":"317.003316ms","start":"2026-02-26T21:21:25.434482Z","end":"2026-02-26T21:21:25.751485Z","steps":["trace[1721765044] 'range keys from in-memory index tree' (duration: 316.651748ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:25.751212Z","caller":"traceutil/trace.go:171","msg":"trace[438821344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10201; }","duration":"259.596343ms","start":"2026-02-26T21:21:25.491592Z","end":"2026-02-26T21:21:25.751189Z","steps":["trace[438821344] 'range keys from in-memory index tree' (duration: 259.185953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:25.751777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:21:25.434452Z","time spent":"317.226301ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-02-26T21:21:25.916678Z","caller":"traceutil/trace.go:171","msg":"trace[1423083992] transaction","detail":"{read_only:false; response_revision:10206; number_of_response:1; }","duration":"127.158969ms","start":"2026-02-26T21:21:25.789484Z","end":"2026-02-26T21:21:25.916643Z","steps":["trace[1423083992] 'process raft request' (duration: 126.920423ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.755169Z","caller":"traceutil/trace.go:171","msg":"trace[1031656205] transaction","detail":"{read_only:false; response_revision:10236; number_of_response:1; }","duration":"100.169127ms","start":"2026-02-26T21:21:30.654968Z","end":"2026-02-26T21:21:30.755137Z","steps":["trace[1031656205] 'process raft request' (duration: 56.243461ms)","trace[1031656205] 'compare' (duration: 43.805243ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:21:30.79427Z","caller":"traceutil/trace.go:171","msg":"trace[1851793416] transaction","detail":"{read_only:false; response_revision:10238; number_of_response:1; }","duration":"139.117877ms","start":"2026-02-26T21:21:30.655132Z","end":"2026-02-26T21:21:30.794249Z","steps":["trace[1851793416] 'process raft request' (duration: 138.979744ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.794374Z","caller":"traceutil/trace.go:171","msg":"trace[631196494] transaction","detail":"{read_only:false; response_revision:10239; number_of_response:1; }","duration":"136.614282ms","start":"2026-02-26T21:21:30.657724Z","end":"2026-02-26T21:21:30.794338Z","steps":["trace[631196494] 'process raft request' (duration: 136.447278ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:30.794466Z","caller":"traceutil/trace.go:171","msg":"trace[352463300] transaction","detail":"{read_only:false; response_revision:10237; number_of_response:1; }","duration":"139.425674ms","start":"2026-02-26T21:21:30.655006Z","end":"2026-02-26T21:21:30.794432Z","steps":["trace[352463300] 'process raft request' (duration: 138.938552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.803969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.814964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-02-26T21:21:30.804044Z","caller":"traceutil/trace.go:171","msg":"trace[1334677908] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:10241; }","duration":"107.938817ms","start":"2026-02-26T21:21:30.696094Z","end":"2026-02-26T21:21:30.804033Z","steps":["trace[1334677908] 'agreement among raft nodes before linearized reading' (duration: 107.778823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.804489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.932811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5176"} {"level":"info","ts":"2026-02-26T21:21:30.804571Z","caller":"traceutil/trace.go:171","msg":"trace[1435265276] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10241; }","duration":"107.023444ms","start":"2026-02-26T21:21:30.697539Z","end":"2026-02-26T21:21:30.804563Z","steps":["trace[1435265276] 'agreement among raft nodes before linearized reading' (duration: 106.887009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.804727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.117281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.804782Z","caller":"traceutil/trace.go:171","msg":"trace[1701968664] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10241; }","duration":"108.192974ms","start":"2026-02-26T21:21:30.696582Z","end":"2026-02-26T21:21:30.804775Z","steps":["trace[1701968664] 'agreement among raft nodes before linearized reading' (duration: 108.123782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:21:30.804909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.760088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:21:30.804958Z","caller":"traceutil/trace.go:171","msg":"trace[45961357] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10241; }","duration":"108.832919ms","start":"2026-02-26T21:21:30.696118Z","end":"2026-02-26T21:21:30.80495Z","steps":["trace[45961357] 'agreement among raft nodes before linearized reading' (duration: 108.772287ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:21:39.353253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7815} {"level":"info","ts":"2026-02-26T21:21:39.41489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7815,"took":"59.814585ms","hash":1472883474,"current-db-size-bytes":43917312,"current-db-size":"44 MB","current-db-size-in-use-bytes":28106752,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T21:21:39.414945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1472883474,"revision":7815,"compact-revision":5769} {"level":"info","ts":"2026-02-26T21:26:39.3634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10313} {"level":"info","ts":"2026-02-26T21:26:39.441939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10313,"took":"74.679384ms","hash":2231948889,"current-db-size-bytes":43917312,"current-db-size":"44 MB","current-db-size-in-use-bytes":24403968,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T21:26:39.442007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2231948889,"revision":10313,"compact-revision":7815} {"level":"info","ts":"2026-02-26T21:27:20.291342Z","caller":"traceutil/trace.go:171","msg":"trace[720663481] transaction","detail":"{read_only:false; response_revision:12449; number_of_response:1; }","duration":"200.980268ms","start":"2026-02-26T21:27:20.090325Z","end":"2026-02-26T21:27:20.291306Z","steps":["trace[720663481] 'process raft request' (duration: 200.801424ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:33.851957Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032a0.wal"} {"level":"info","ts":"2026-02-26T21:27:39.462484Z","caller":"traceutil/trace.go:171","msg":"trace[625454336] linearizableReadLoop","detail":"{readStateIndex:12984; appliedIndex:12983; }","duration":"130.254837ms","start":"2026-02-26T21:27:39.332201Z","end":"2026-02-26T21:27:39.462456Z","steps":["trace[625454336] 'read index received' (duration: 130.062483ms)","trace[625454336] 'applied index is now lower than readState.Index' (duration: 191.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:39.462551Z","caller":"traceutil/trace.go:171","msg":"trace[935924844] transaction","detail":"{read_only:false; response_revision:12538; number_of_response:1; }","duration":"303.582298ms","start":"2026-02-26T21:27:39.158931Z","end":"2026-02-26T21:27:39.462513Z","steps":["trace[935924844] 'process raft request' (duration: 303.387183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:39.462787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:39.1589Z","time spent":"303.728191ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:27:39.462976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.696148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:39.463151Z","caller":"traceutil/trace.go:171","msg":"trace[30124090] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:12538; }","duration":"130.999416ms","start":"2026-02-26T21:27:39.332123Z","end":"2026-02-26T21:27:39.463122Z","steps":["trace[30124090] 'agreement among raft nodes before linearized reading' (duration: 130.411341ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:46.781262Z","caller":"traceutil/trace.go:171","msg":"trace[1566976854] transaction","detail":"{read_only:false; response_revision:12571; number_of_response:1; }","duration":"113.563449ms","start":"2026-02-26T21:27:46.667666Z","end":"2026-02-26T21:27:46.781229Z","steps":["trace[1566976854] 'process raft request' (duration: 113.267871ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:49.11072Z","caller":"traceutil/trace.go:171","msg":"trace[1594013302] transaction","detail":"{read_only:false; response_revision:12599; number_of_response:1; }","duration":"104.456307ms","start":"2026-02-26T21:27:49.006245Z","end":"2026-02-26T21:27:49.110701Z","steps":["trace[1594013302] 'process raft request' (duration: 104.328494ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:49.739585Z","caller":"traceutil/trace.go:171","msg":"trace[520430958] linearizableReadLoop","detail":"{readStateIndex:13049; appliedIndex:13048; }","duration":"182.022474ms","start":"2026-02-26T21:27:49.557534Z","end":"2026-02-26T21:27:49.739556Z","steps":["trace[520430958] 'read index received' (duration: 181.370577ms)","trace[520430958] 'applied index is now lower than readState.Index' (duration: 650.436µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:49.739696Z","caller":"traceutil/trace.go:171","msg":"trace[821045687] transaction","detail":"{read_only:false; response_revision:12600; number_of_response:1; }","duration":"209.404705ms","start":"2026-02-26T21:27:49.530247Z","end":"2026-02-26T21:27:49.739652Z","steps":["trace[821045687] 'process raft request' (duration: 208.723127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:49.739907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.351471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T21:27:49.739982Z","caller":"traceutil/trace.go:171","msg":"trace[429584853] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:12600; }","duration":"182.476055ms","start":"2026-02-26T21:27:49.557488Z","end":"2026-02-26T21:27:49.739964Z","steps":["trace[429584853] 'agreement among raft nodes before linearized reading' (duration: 182.23359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:49.739818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.308719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:49.740044Z","caller":"traceutil/trace.go:171","msg":"trace[1738338066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12600; }","duration":"123.584236ms","start":"2026-02-26T21:27:49.616446Z","end":"2026-02-26T21:27:49.74003Z","steps":["trace[1738338066] 'agreement among raft nodes before linearized reading' (duration: 123.311409ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:50.134677Z","caller":"traceutil/trace.go:171","msg":"trace[1436954265] transaction","detail":"{read_only:false; response_revision:12604; number_of_response:1; }","duration":"192.138993ms","start":"2026-02-26T21:27:49.942508Z","end":"2026-02-26T21:27:50.134647Z","steps":["trace[1436954265] 'process raft request' (duration: 138.225942ms)","trace[1436954265] 'compare' (duration: 53.785468ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:57.124732Z","caller":"traceutil/trace.go:171","msg":"trace[1985283457] linearizableReadLoop","detail":"{readStateIndex:13087; appliedIndex:13086; }","duration":"242.055291ms","start":"2026-02-26T21:27:56.882657Z","end":"2026-02-26T21:27:57.124713Z","steps":["trace[1985283457] 'read index received' (duration: 241.920978ms)","trace[1985283457] 'applied index is now lower than readState.Index' (duration: 133.303µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:57.124838Z","caller":"traceutil/trace.go:171","msg":"trace[388911471] transaction","detail":"{read_only:false; response_revision:12637; number_of_response:1; }","duration":"242.80155ms","start":"2026-02-26T21:27:56.882002Z","end":"2026-02-26T21:27:57.124803Z","steps":["trace[388911471] 'process raft request' (duration: 242.580105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:57.124987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.259814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:27:57.125024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.37502ms","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-02-26T21:27:57.125075Z","caller":"traceutil/trace.go:171","msg":"trace[296831723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12637; }","duration":"242.476443ms","start":"2026-02-26T21:27:56.882584Z","end":"2026-02-26T21:27:57.125061Z","steps":["trace[296831723] 'agreement among raft nodes before linearized reading' (duration: 242.249078ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:57.125074Z","caller":"traceutil/trace.go:171","msg":"trace[365906012] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:12637; }","duration":"131.404477ms","start":"2026-02-26T21:27:56.993652Z","end":"2026-02-26T21:27:57.125057Z","steps":["trace[365906012] 'agreement among raft nodes before linearized reading' (duration: 131.227733ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:57.253667Z","caller":"traceutil/trace.go:171","msg":"trace[1278591883] transaction","detail":"{read_only:false; response_revision:12639; number_of_response:1; }","duration":"114.883324ms","start":"2026-02-26T21:27:57.138752Z","end":"2026-02-26T21:27:57.253635Z","steps":["trace[1278591883] 'process raft request' (duration: 48.73738ms)","trace[1278591883] 'compare' (duration: 66.015601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:27:57.952588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.200764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:27:57.952621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.053583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:57.952656Z","caller":"traceutil/trace.go:171","msg":"trace[992273329] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:12639; }","duration":"392.122185ms","start":"2026-02-26T21:27:57.560523Z","end":"2026-02-26T21:27:57.952646Z","steps":["trace[992273329] 'count revisions from in-memory index tree' (duration: 391.973412ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:57.952656Z","caller":"traceutil/trace.go:171","msg":"trace[757436213] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12639; }","duration":"427.321237ms","start":"2026-02-26T21:27:57.525321Z","end":"2026-02-26T21:27:57.952642Z","steps":["trace[757436213] 'count revisions from in-memory index tree' (duration: 427.066391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:57.952684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.560504Z","time spent":"392.172267ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:27:57.95269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.525302Z","time spent":"427.380469ms","remote":"127.0.0.1:53818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:27:57.952865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.586659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:57.952952Z","caller":"traceutil/trace.go:171","msg":"trace[2062256665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12639; }","duration":"337.738092ms","start":"2026-02-26T21:27:57.615195Z","end":"2026-02-26T21:27:57.952933Z","steps":["trace[2062256665] 'range keys from in-memory index tree' (duration: 337.513057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:57.953002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.615175Z","time spent":"337.817694ms","remote":"127.0.0.1:37038","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-02-26T21:27:58.304867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846778112,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:27:58.39863Z","caller":"traceutil/trace.go:171","msg":"trace[1930846384] linearizableReadLoop","detail":"{readStateIndex:13091; appliedIndex:13089; }","duration":"594.174813ms","start":"2026-02-26T21:27:57.804427Z","end":"2026-02-26T21:27:58.398601Z","steps":["trace[1930846384] 'read index received' (duration: 523.85031ms)","trace[1930846384] 'applied index is now lower than readState.Index' (duration: 70.323283ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:58.398722Z","caller":"traceutil/trace.go:171","msg":"trace[728222496] transaction","detail":"{read_only:false; response_revision:12640; number_of_response:1; }","duration":"625.963477ms","start":"2026-02-26T21:27:57.772673Z","end":"2026-02-26T21:27:58.398636Z","steps":["trace[728222496] 'process raft request' (duration: 625.725701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:58.398886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.77265Z","time spent":"626.150762ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:27:58.399012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.577663ms","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-02-26T21:27:58.399197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.325049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-02-26T21:27:58.399235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.208596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:58.39925Z","caller":"traceutil/trace.go:171","msg":"trace[2059774404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12640; }","duration":"555.850011ms","start":"2026-02-26T21:27:57.843379Z","end":"2026-02-26T21:27:58.399229Z","steps":["trace[2059774404] 'agreement among raft nodes before linearized reading' (duration: 555.44032ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:58.39928Z","caller":"traceutil/trace.go:171","msg":"trace[1192654968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12640; }","duration":"445.270848ms","start":"2026-02-26T21:27:57.953994Z","end":"2026-02-26T21:27:58.399264Z","steps":["trace[1192654968] 'agreement among raft nodes before linearized reading' (duration: 445.215856ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:58.399285Z","caller":"traceutil/trace.go:171","msg":"trace[124750512] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12640; }","duration":"427.392921ms","start":"2026-02-26T21:27:57.971862Z","end":"2026-02-26T21:27:58.399255Z","steps":["trace[124750512] 'agreement among raft nodes before linearized reading' (duration: 427.259667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:58.399323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.953979Z","time spent":"445.335779ms","remote":"127.0.0.1:37040","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-02-26T21:27:58.399362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.971839Z","time spent":"427.507003ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-26T21:27:58.3993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.843359Z","time spent":"555.928742ms","remote":"127.0.0.1:37296","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-02-26T21:27:58.399059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.607494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rqbs\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-02-26T21:27:58.399173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.164279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:27:58.399551Z","caller":"traceutil/trace.go:171","msg":"trace[1605554154] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rqbs; range_end:; response_count:1; response_revision:12640; }","duration":"595.119506ms","start":"2026-02-26T21:27:57.804382Z","end":"2026-02-26T21:27:58.399501Z","steps":["trace[1605554154] 'agreement among raft nodes before linearized reading' (duration: 594.359937ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:58.399596Z","caller":"traceutil/trace.go:171","msg":"trace[1059832981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12640; }","duration":"234.61012ms","start":"2026-02-26T21:27:58.164969Z","end":"2026-02-26T21:27:58.399579Z","steps":["trace[1059832981] 'agreement among raft nodes before linearized reading' (duration: 234.129279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:58.399057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.456995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T21:27:58.399665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:57.804364Z","time spent":"595.242519ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-7rqbs\" "} {"level":"info","ts":"2026-02-26T21:27:58.399689Z","caller":"traceutil/trace.go:171","msg":"trace[1483676184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12640; }","duration":"393.141832ms","start":"2026-02-26T21:27:58.006536Z","end":"2026-02-26T21:27:58.399678Z","steps":["trace[1483676184] 'agreement among raft nodes before linearized reading' (duration: 392.359342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:58.399733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.006515Z","time spent":"393.208064ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:27:58.98595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724566846778117,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:27:59.095206Z","caller":"traceutil/trace.go:171","msg":"trace[646787432] transaction","detail":"{read_only:false; response_revision:12643; number_of_response:1; }","duration":"688.242222ms","start":"2026-02-26T21:27:58.406947Z","end":"2026-02-26T21:27:59.09519Z","steps":["trace[646787432] 'process raft request' (duration: 688.186451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.095306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.406919Z","time spent":"688.335785ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:27:59.09532Z","caller":"traceutil/trace.go:171","msg":"trace[1726289223] transaction","detail":"{read_only:false; response_revision:12642; number_of_response:1; }","duration":"689.217857ms","start":"2026-02-26T21:27:58.406054Z","end":"2026-02-26T21:27:59.095272Z","steps":["trace[1726289223] 'process raft request' (duration: 688.986682ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:27:59.09541Z","caller":"traceutil/trace.go:171","msg":"trace[457463904] transaction","detail":"{read_only:false; response_revision:12644; number_of_response:1; }","duration":"687.970726ms","start":"2026-02-26T21:27:58.407361Z","end":"2026-02-26T21:27:59.095332Z","steps":["trace[457463904] 'process raft request' (duration: 687.806992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.095514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.406034Z","time spent":"689.37844ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:27:59.095529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.40735Z","time spent":"688.12189ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:27:59.09679Z","caller":"traceutil/trace.go:171","msg":"trace[338520009] linearizableReadLoop","detail":"{readStateIndex:13096; appliedIndex:13092; }","duration":"611.184408ms","start":"2026-02-26T21:27:58.485587Z","end":"2026-02-26T21:27:59.096772Z","steps":["trace[338520009] 'read index received' (duration: 609.422404ms)","trace[338520009] 'applied index is now lower than readState.Index' (duration: 1.761474ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:27:59.096869Z","caller":"traceutil/trace.go:171","msg":"trace[1477917760] transaction","detail":"{read_only:false; response_revision:12645; number_of_response:1; }","duration":"388.135823ms","start":"2026-02-26T21:27:58.708713Z","end":"2026-02-26T21:27:59.096849Z","steps":["trace[1477917760] 'process raft request' (duration: 387.936438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.096963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.708683Z","time spent":"388.228666ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:27:59.096974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.369953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:27:59.097009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.498979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:27:59.09703Z","caller":"traceutil/trace.go:171","msg":"trace[2115764048] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:12645; }","duration":"611.481926ms","start":"2026-02-26T21:27:58.485535Z","end":"2026-02-26T21:27:59.097017Z","steps":["trace[2115764048] 'agreement among raft nodes before linearized reading' (duration: 611.328822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.097031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.138016ms","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-02-26T21:27:59.097068Z","caller":"traceutil/trace.go:171","msg":"trace[1883390670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12645; }","duration":"511.177337ms","start":"2026-02-26T21:27:58.585879Z","end":"2026-02-26T21:27:59.097057Z","steps":["trace[1883390670] 'agreement among raft nodes before linearized reading' (duration: 511.036343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.097078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.485515Z","time spent":"611.539278ms","remote":"127.0.0.1:57870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:27:59.09706Z","caller":"traceutil/trace.go:171","msg":"trace[1881467145] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:12645; }","duration":"284.579251ms","start":"2026-02-26T21:27:58.812467Z","end":"2026-02-26T21:27:59.097046Z","steps":["trace[1881467145] 'agreement among raft nodes before linearized reading' (duration: 284.489148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.097101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.585825Z","time spent":"511.269729ms","remote":"127.0.0.1:37296","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-02-26T21:27:59.097246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.529142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:27:59.097289Z","caller":"traceutil/trace.go:171","msg":"trace[418118158] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:12645; }","duration":"505.600384ms","start":"2026-02-26T21:27:58.591677Z","end":"2026-02-26T21:27:59.097278Z","steps":["trace[418118158] 'agreement among raft nodes before linearized reading' (duration: 505.494341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.097313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.591658Z","time spent":"505.649274ms","remote":"127.0.0.1:37198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":62,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:27:59.097635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.085565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929855"} {"level":"info","ts":"2026-02-26T21:27:59.09768Z","caller":"traceutil/trace.go:171","msg":"trace[823918970] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:12645; }","duration":"182.163557ms","start":"2026-02-26T21:27:58.915505Z","end":"2026-02-26T21:27:59.097669Z","steps":["trace[823918970] 'agreement among raft nodes before linearized reading' (duration: 181.476439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.097682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.93642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T21:27:59.097915Z","caller":"traceutil/trace.go:171","msg":"trace[678129758] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12645; }","duration":"303.008993ms","start":"2026-02-26T21:27:58.794698Z","end":"2026-02-26T21:27:59.097707Z","steps":["trace[678129758] 'agreement among raft nodes before linearized reading' (duration: 302.407437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:27:59.098009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:27:58.794676Z","time spent":"303.28371ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-26T21:28:00.565165Z","caller":"traceutil/trace.go:171","msg":"trace[1961431300] transaction","detail":"{read_only:false; response_revision:12748; number_of_response:1; }","duration":"101.436559ms","start":"2026-02-26T21:28:00.463702Z","end":"2026-02-26T21:28:00.565138Z","steps":["trace[1961431300] 'process raft request' (duration: 66.896273ms)","trace[1961431300] 'compare' (duration: 34.176346ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:28:03.232078Z","caller":"traceutil/trace.go:171","msg":"trace[959279439] transaction","detail":"{read_only:false; response_revision:12778; number_of_response:1; }","duration":"116.241998ms","start":"2026-02-26T21:28:03.11581Z","end":"2026-02-26T21:28:03.232052Z","steps":["trace[959279439] 'process raft request' (duration: 83.458758ms)","trace[959279439] 'compare' (duration: 32.494383ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:28:03.232265Z","caller":"traceutil/trace.go:171","msg":"trace[1137442870] linearizableReadLoop","detail":"{readStateIndex:13232; appliedIndex:13230; }","duration":"111.130417ms","start":"2026-02-26T21:28:03.121097Z","end":"2026-02-26T21:28:03.232227Z","steps":["trace[1137442870] 'read index received' (duration: 78.190733ms)","trace[1137442870] 'applied index is now lower than readState.Index' (duration: 32.938774ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:28:03.232303Z","caller":"traceutil/trace.go:171","msg":"trace[1764905655] transaction","detail":"{read_only:false; response_revision:12779; number_of_response:1; }","duration":"116.460504ms","start":"2026-02-26T21:28:03.115815Z","end":"2026-02-26T21:28:03.232275Z","steps":["trace[1764905655] 'process raft request' (duration: 116.177907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:28:03.232423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.308142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T21:28:03.232457Z","caller":"traceutil/trace.go:171","msg":"trace[730263616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12779; }","duration":"111.378423ms","start":"2026-02-26T21:28:03.121071Z","end":"2026-02-26T21:28:03.23245Z","steps":["trace[730263616] 'agreement among raft nodes before linearized reading' (duration: 111.25263ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:03.248156Z","caller":"traceutil/trace.go:171","msg":"trace[1434929718] transaction","detail":"{read_only:false; response_revision:12780; number_of_response:1; }","duration":"102.950798ms","start":"2026-02-26T21:28:03.145179Z","end":"2026-02-26T21:28:03.24813Z","steps":["trace[1434929718] 'process raft request' (duration: 102.783584ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:03.380075Z","caller":"traceutil/trace.go:171","msg":"trace[1199247120] transaction","detail":"{read_only:false; response_revision:12781; number_of_response:1; }","duration":"144.932363ms","start":"2026-02-26T21:28:03.235095Z","end":"2026-02-26T21:28:03.380027Z","steps":["trace[1199247120] 'process raft request' (duration: 142.486971ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:28:03.380091Z","caller":"traceutil/trace.go:171","msg":"trace[1999001968] transaction","detail":"{read_only:false; response_revision:12782; number_of_response:1; }","duration":"128.531942ms","start":"2026-02-26T21:28:03.251501Z","end":"2026-02-26T21:28:03.380033Z","steps":["trace[1999001968] 'process raft request' (duration: 128.466281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:04.575311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.322141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:29:04.575349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.380263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:29:04.575447Z","caller":"traceutil/trace.go:171","msg":"trace[423050900] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13621; }","duration":"271.507087ms","start":"2026-02-26T21:29:04.303906Z","end":"2026-02-26T21:29:04.575413Z","steps":["trace[423050900] 'range keys from in-memory index tree' (duration: 271.164468ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:04.575495Z","caller":"traceutil/trace.go:171","msg":"trace[840845486] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13621; }","duration":"271.602229ms","start":"2026-02-26T21:29:04.303855Z","end":"2026-02-26T21:29:04.575457Z","steps":["trace[840845486] 'range keys from in-memory index tree' (duration: 271.194208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:04.575573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.695302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mrdqt\" ","response":"range_response_count:1 size:9793"} {"level":"info","ts":"2026-02-26T21:29:04.575688Z","caller":"traceutil/trace.go:171","msg":"trace[1663013605] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mrdqt; range_end:; response_count:1; response_revision:13621; }","duration":"296.878967ms","start":"2026-02-26T21:29:04.278785Z","end":"2026-02-26T21:29:04.575664Z","steps":["trace[1663013605] 'range keys from in-memory index tree' (duration: 296.463626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:04.575716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.170159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-02-26T21:29:04.575751Z","caller":"traceutil/trace.go:171","msg":"trace[1073449454] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13621; }","duration":"266.237931ms","start":"2026-02-26T21:29:04.309503Z","end":"2026-02-26T21:29:04.575741Z","steps":["trace[1073449454] 'range keys from in-memory index tree' (duration: 265.986914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:29:04.575902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.927987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:29:04.576014Z","caller":"traceutil/trace.go:171","msg":"trace[2055610311] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13621; }","duration":"272.083131ms","start":"2026-02-26T21:29:04.303904Z","end":"2026-02-26T21:29:04.575987Z","steps":["trace[2055610311] 'range keys from in-memory index tree' (duration: 271.796773ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:29:38.973129Z","caller":"traceutil/trace.go:171","msg":"trace[1517528108] transaction","detail":"{read_only:false; response_revision:13792; number_of_response:1; }","duration":"188.342682ms","start":"2026-02-26T21:29:38.784752Z","end":"2026-02-26T21:29:38.973095Z","steps":["trace[1517528108] 'process raft request' (duration: 188.168908ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:31:39.371128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12176} {"level":"info","ts":"2026-02-26T21:31:39.445158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12176,"took":"71.274269ms","hash":2139994452,"current-db-size-bytes":43917312,"current-db-size":"44 MB","current-db-size-in-use-bytes":28479488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T21:31:39.445278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2139994452,"revision":12176,"compact-revision":10313} {"level":"info","ts":"2026-02-26T21:35:25.233016Z","caller":"traceutil/trace.go:171","msg":"trace[1318830192] transaction","detail":"{read_only:false; response_revision:15984; number_of_response:1; }","duration":"462.797403ms","start":"2026-02-26T21:35:24.770191Z","end":"2026-02-26T21:35:25.232988Z","steps":["trace[1318830192] 'process raft request' (duration: 462.619599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:35:25.233205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:35:24.770172Z","time spent":"462.939477ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:35:25.330531Z","caller":"traceutil/trace.go:171","msg":"trace[119389748] linearizableReadLoop","detail":"{readStateIndex:16554; appliedIndex:16552; }","duration":"482.396827ms","start":"2026-02-26T21:35:24.848105Z","end":"2026-02-26T21:35:25.330502Z","steps":["trace[119389748] 'read index received' (duration: 384.779223ms)","trace[119389748] 'applied index is now lower than readState.Index' (duration: 97.616484ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:35:25.330675Z","caller":"traceutil/trace.go:171","msg":"trace[841536417] transaction","detail":"{read_only:false; response_revision:15985; number_of_response:1; }","duration":"508.392524ms","start":"2026-02-26T21:35:24.82224Z","end":"2026-02-26T21:35:25.330633Z","steps":["trace[841536417] 'process raft request' (duration: 488.133695ms)","trace[841536417] 'compare' (duration: 19.964392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:35:25.33076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.20985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:35:25.330799Z","caller":"traceutil/trace.go:171","msg":"trace[563819380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15985; }","duration":"372.309092ms","start":"2026-02-26T21:35:24.958478Z","end":"2026-02-26T21:35:25.330787Z","steps":["trace[563819380] 'agreement among raft nodes before linearized reading' (duration: 372.22603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:35:25.330835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:35:24.958454Z","time spent":"372.372754ms","remote":"127.0.0.1:37038","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-02-26T21:35:25.330882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:35:24.822211Z","time spent":"508.565169ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:35:25.331008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.563843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-26T21:35:25.331137Z","caller":"traceutil/trace.go:171","msg":"trace[2044577309] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:15985; }","duration":"470.740707ms","start":"2026-02-26T21:35:24.860376Z","end":"2026-02-26T21:35:25.331117Z","steps":["trace[2044577309] 'agreement among raft nodes before linearized reading' (duration: 470.313606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:35:25.331214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:35:24.860359Z","time spent":"470.83898ms","remote":"127.0.0.1:37198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-02-26T21:35:25.331235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.126646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mrdqt\" ","response":"range_response_count:1 size:9793"} {"level":"info","ts":"2026-02-26T21:35:25.331293Z","caller":"traceutil/trace.go:171","msg":"trace[1502285545] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mrdqt; range_end:; response_count:1; response_revision:15985; }","duration":"483.212088ms","start":"2026-02-26T21:35:24.848067Z","end":"2026-02-26T21:35:25.331279Z","steps":["trace[1502285545] 'agreement among raft nodes before linearized reading' (duration: 482.579072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:35:25.331343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:35:24.848055Z","time spent":"483.276279ms","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9817,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mrdqt\" "} {"level":"warn","ts":"2026-02-26T21:35:25.331612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.03628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-26T21:35:25.331721Z","caller":"traceutil/trace.go:171","msg":"trace[1075786292] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:15985; }","duration":"470.221995ms","start":"2026-02-26T21:35:24.861474Z","end":"2026-02-26T21:35:25.331696Z","steps":["trace[1075786292] 'agreement among raft nodes before linearized reading' (duration: 469.788813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:35:25.331771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:35:24.861466Z","time spent":"470.296757ms","remote":"127.0.0.1:37198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-02-26T21:36:39.377693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14593} {"level":"info","ts":"2026-02-26T21:36:39.473829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14593,"took":"92.003031ms","hash":2137265723,"current-db-size-bytes":43917312,"current-db-size":"44 MB","current-db-size-in-use-bytes":23830528,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-26T21:36:39.473907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2137265723,"revision":14593,"compact-revision":12176}