{"level":"warn","ts":"2026-03-01T21:12:21.922117Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T21:12:21.92219Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-03-01T21:12:21.922255Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T21:12:21.922269Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-01T21:12:21.922303Z","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-03-01T21:12:21.922893Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-03-01T21:12:21.922965Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-03-01T21:12:21.926995Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.822484ms"} {"level":"info","ts":"2026-03-01T21:12:21.936958Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-03-01T21:12:21.937066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T21:12:21.937091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-03-01T21:12:21.937105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T21:12:21.937123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-03-01T21:12:21.937179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-03-01T21:12:21.944444Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T21:12:21.947272Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T21:12:21.949246Z","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-03-01T21:12:21.952247Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T21:12:21.952327Z","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-03-01T21:12:21.952398Z","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-03-01T21:12:21.952424Z","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-03-01T21:12:21.952482Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T21:12:21.954081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-03-01T21:12:21.954201Z","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-03-01T21:12:21.954271Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-01T21:12:21.954283Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-01T21:12:21.954497Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T21:12:21.954513Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-01T21:12:21.954459Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T21:12:22.93736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T21:12:22.93741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T21:12:22.937442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-03-01T21:12:22.937457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-03-01T21:12:22.937466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-01T21:12:22.937487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-03-01T21:12:22.937505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-01T21:12:22.938456Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T21:12:22.93846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T21:12:22.938468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:12:22.938496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T21:12:22.938678Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T21:12:22.938713Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T21:12:22.939388Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:12:22.939556Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:12:22.939588Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:12:22.94085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-03-01T21:12:22.941914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T21:12:47.954198Z","caller":"traceutil/trace.go:171","msg":"trace[1976079672] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"181.315117ms","start":"2026-03-01T21:12:47.772858Z","end":"2026-03-01T21:12:47.954173Z","steps":["trace[1976079672] 'process raft request' (duration: 181.168394ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:58.100918Z","caller":"traceutil/trace.go:171","msg":"trace[860806760] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"165.365119ms","start":"2026-03-01T21:12:57.935532Z","end":"2026-03-01T21:12:58.100897Z","steps":["trace[860806760] 'process raft request' (duration: 165.028701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:59.022288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830177123448241,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T21:12:59.265806Z","caller":"traceutil/trace.go:171","msg":"trace[2117606417] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"965.508442ms","start":"2026-03-01T21:12:58.300275Z","end":"2026-03-01T21:12:59.265784Z","steps":["trace[2117606417] 'process raft request' (duration: 965.372069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:59.266378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:58.300246Z","time spent":"965.617095ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4749,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:12:59.308693Z","caller":"traceutil/trace.go:171","msg":"trace[1174637878] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:567; }","duration":"786.689194ms","start":"2026-03-01T21:12:58.521987Z","end":"2026-03-01T21:12:59.308676Z","steps":["trace[1174637878] 'read index received' (duration: 743.712338ms)","trace[1174637878] 'applied index is now lower than readState.Index' (duration: 42.975686ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:12:59.308956Z","caller":"traceutil/trace.go:171","msg":"trace[951708468] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"791.441644ms","start":"2026-03-01T21:12:58.517503Z","end":"2026-03-01T21:12:59.308944Z","steps":["trace[951708468] 'process raft request' (duration: 791.085306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:59.309005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.536769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:12:59.309054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:58.517475Z","time spent":"791.516275ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:12:59.309086Z","caller":"traceutil/trace.go:171","msg":"trace[1253295294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"776.658542ms","start":"2026-03-01T21:12:58.532411Z","end":"2026-03-01T21:12:59.30907Z","steps":["trace[1253295294] 'agreement among raft nodes before linearized reading' (duration: 776.529839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:59.309087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.093413ms","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-03-01T21:12:59.309119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:58.5324Z","time spent":"776.712133ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-01T21:12:59.309135Z","caller":"traceutil/trace.go:171","msg":"trace[1965042167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"787.185665ms","start":"2026-03-01T21:12:58.521935Z","end":"2026-03-01T21:12:59.309121Z","steps":["trace[1965042167] 'agreement among raft nodes before linearized reading' (duration: 787.086683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:59.309129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.981273ms","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-03-01T21:12:59.309173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:58.521868Z","time spent":"787.294567ms","remote":"127.0.0.1:43472","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-03-01T21:12:59.309177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.524018ms","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-03-01T21:12:59.309219Z","caller":"traceutil/trace.go:171","msg":"trace[1636114430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"331.127916ms","start":"2026-03-01T21:12:58.978071Z","end":"2026-03-01T21:12:59.309199Z","steps":["trace[1636114430] 'agreement among raft nodes before linearized reading' (duration: 330.919491ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:59.30924Z","caller":"traceutil/trace.go:171","msg":"trace[580870532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"335.635531ms","start":"2026-03-01T21:12:58.973588Z","end":"2026-03-01T21:12:59.309224Z","steps":["trace[580870532] 'agreement among raft nodes before linearized reading' (duration: 335.499877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:59.309273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:58.978051Z","time spent":"331.212488ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T21:12:59.309285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:58.973569Z","time spent":"335.704543ms","remote":"127.0.0.1:43472","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":"info","ts":"2026-03-01T21:13:01.252246Z","caller":"traceutil/trace.go:171","msg":"trace[36775133] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"164.545409ms","start":"2026-03-01T21:13:01.087675Z","end":"2026-03-01T21:13:01.252221Z","steps":["trace[36775133] 'read index received' (duration: 164.233852ms)","trace[36775133] 'applied index is now lower than readState.Index' (duration: 310.037µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:13:01.252422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.738094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cert-manager-controller-orders\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:13:01.25239Z","caller":"traceutil/trace.go:171","msg":"trace[227520381] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"166.854743ms","start":"2026-03-01T21:13:01.0855Z","end":"2026-03-01T21:13:01.252355Z","steps":["trace[227520381] 'process raft request' (duration: 166.567506ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:01.252509Z","caller":"traceutil/trace.go:171","msg":"trace[6528150] range","detail":"{range_begin:/registry/clusterroles/cert-manager-controller-orders; range_end:; response_count:0; response_revision:578; }","duration":"164.850876ms","start":"2026-03-01T21:13:01.0876Z","end":"2026-03-01T21:13:01.252451Z","steps":["trace[6528150] 'agreement among raft nodes before linearized reading' (duration: 164.737543ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:18.191341Z","caller":"traceutil/trace.go:171","msg":"trace[1088581575] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"131.643205ms","start":"2026-03-01T21:13:18.059677Z","end":"2026-03-01T21:13:18.19132Z","steps":["trace[1088581575] 'process raft request' (duration: 131.485841ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:18.528205Z","caller":"traceutil/trace.go:171","msg":"trace[1013488794] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"147.442439ms","start":"2026-03-01T21:13:18.380726Z","end":"2026-03-01T21:13:18.528169Z","steps":["trace[1013488794] 'read index received' (duration: 147.198423ms)","trace[1013488794] 'applied index is now lower than readState.Index' (duration: 242.736µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:18.528372Z","caller":"traceutil/trace.go:171","msg":"trace[216065070] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"329.310092ms","start":"2026-03-01T21:13:18.198995Z","end":"2026-03-01T21:13:18.528305Z","steps":["trace[216065070] 'process raft request' (duration: 328.953444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:18.528532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:18.198948Z","time spent":"329.499406ms","remote":"127.0.0.1:43694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:18.528527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.788636ms","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-03-01T21:13:18.52861Z","caller":"traceutil/trace.go:171","msg":"trace[1474816444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"147.925179ms","start":"2026-03-01T21:13:18.380664Z","end":"2026-03-01T21:13:18.528589Z","steps":["trace[1474816444] 'agreement among raft nodes before linearized reading' (duration: 147.689484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:19.274368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830177123448906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T21:13:19.698533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.300857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:13:19.698781Z","caller":"traceutil/trace.go:171","msg":"trace[2068909472] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.165280905s","start":"2026-03-01T21:13:18.533472Z","end":"2026-03-01T21:13:19.698753Z","steps":["trace[2068909472] 'process raft request' (duration: 804.299653ms)","trace[2068909472] 'compare' (duration: 360.154543ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:13:19.698926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:18.53344Z","time spent":"1.165443429s","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:13:19.701971Z","caller":"traceutil/trace.go:171","msg":"trace[78607785] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"927.699598ms","start":"2026-03-01T21:13:18.774241Z","end":"2026-03-01T21:13:19.701941Z","steps":["trace[78607785] 'read index received' (duration: 563.493531ms)","trace[78607785] 'applied index is now lower than readState.Index' (duration: 364.204287ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:19.702032Z","caller":"traceutil/trace.go:171","msg":"trace[373537805] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.163902693s","start":"2026-03-01T21:13:18.538102Z","end":"2026-03-01T21:13:19.702005Z","steps":["trace[373537805] 'process raft request' (duration: 1.163709169s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:19.702268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.912308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:13:19.702371Z","caller":"traceutil/trace.go:171","msg":"trace[1261629045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"167.04729ms","start":"2026-03-01T21:13:19.53531Z","end":"2026-03-01T21:13:19.702358Z","steps":["trace[1261629045] 'agreement among raft nodes before linearized reading' (duration: 166.902397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:19.702329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:18.538084Z","time spent":"1.164161069s","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:19.702378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.052525ms","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-03-01T21:13:19.702565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.938807ms","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-03-01T21:13:19.702593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.278406ms","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-03-01T21:13:19.702639Z","caller":"traceutil/trace.go:171","msg":"trace[90130507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"928.418666ms","start":"2026-03-01T21:13:18.774174Z","end":"2026-03-01T21:13:19.702592Z","steps":["trace[90130507] 'agreement among raft nodes before linearized reading' (duration: 927.868702ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:19.702693Z","caller":"traceutil/trace.go:171","msg":"trace[105262729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"265.416539ms","start":"2026-03-01T21:13:19.437256Z","end":"2026-03-01T21:13:19.702672Z","steps":["trace[105262729] 'agreement among raft nodes before linearized reading' (duration: 264.986189ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:19.702688Z","caller":"traceutil/trace.go:171","msg":"trace[1434118806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"271.08114ms","start":"2026-03-01T21:13:19.431567Z","end":"2026-03-01T21:13:19.702649Z","steps":["trace[1434118806] 'agreement among raft nodes before linearized reading' (duration: 270.70412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:19.70271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:18.774149Z","time spent":"928.539848ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T21:13:19.702894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.451992ms","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-03-01T21:13:19.702978Z","caller":"traceutil/trace.go:171","msg":"trace[797024611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"272.560145ms","start":"2026-03-01T21:13:19.430386Z","end":"2026-03-01T21:13:19.702946Z","steps":["trace[797024611] 'agreement among raft nodes before linearized reading' (duration: 271.806887ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:46.165617Z","caller":"traceutil/trace.go:171","msg":"trace[2067581411] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1225; }","duration":"325.471813ms","start":"2026-03-01T21:13:45.84012Z","end":"2026-03-01T21:13:46.165592Z","steps":["trace[2067581411] 'read index received' (duration: 4.928854ms)","trace[2067581411] 'applied index is now lower than readState.Index' (duration: 320.541679ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:46.165653Z","caller":"traceutil/trace.go:171","msg":"trace[1991014783] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"325.708349ms","start":"2026-03-01T21:13:45.839908Z","end":"2026-03-01T21:13:46.165616Z","steps":["trace[1991014783] 'process raft request' (duration: 325.499284ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:46.165724Z","caller":"traceutil/trace.go:171","msg":"trace[1686634669] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"325.78652ms","start":"2026-03-01T21:13:45.839893Z","end":"2026-03-01T21:13:46.165679Z","steps":["trace[1686634669] 'process raft request' (duration: 325.656027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:46.165853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:45.839888Z","time spent":"325.861172ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:46.1659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:45.83987Z","time spent":"325.940203ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:46.165865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.716817ms","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-03-01T21:13:46.165966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.379895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-01T21:13:46.166045Z","caller":"traceutil/trace.go:171","msg":"trace[1336921285] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1192; }","duration":"297.498068ms","start":"2026-03-01T21:13:45.868527Z","end":"2026-03-01T21:13:46.166025Z","steps":["trace[1336921285] 'agreement among raft nodes before linearized reading' (duration: 297.282353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:46.166036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.909403ms","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-03-01T21:13:46.16605Z","caller":"traceutil/trace.go:171","msg":"trace[141778110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1192; }","duration":"321.899111ms","start":"2026-03-01T21:13:45.844106Z","end":"2026-03-01T21:13:46.166005Z","steps":["trace[141778110] 'agreement among raft nodes before linearized reading' (duration: 321.634375ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:46.166105Z","caller":"traceutil/trace.go:171","msg":"trace[1860181358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1192; }","duration":"326.016445ms","start":"2026-03-01T21:13:45.84007Z","end":"2026-03-01T21:13:46.166087Z","steps":["trace[1860181358] 'agreement among raft nodes before linearized reading' (duration: 325.656867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:46.166115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:45.844097Z","time spent":"322.000193ms","remote":"127.0.0.1:43472","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-03-01T21:13:46.166149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:45.840057Z","time spent":"326.080137ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T21:14:07.125717Z","caller":"traceutil/trace.go:171","msg":"trace[798956490] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"122.133367ms","start":"2026-03-01T21:14:07.003553Z","end":"2026-03-01T21:14:07.125686Z","steps":["trace[798956490] 'process raft request' (duration: 121.880871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:07.934646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.995271ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:14:07.934784Z","caller":"traceutil/trace.go:171","msg":"trace[507886586] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1563; }","duration":"419.155904ms","start":"2026-03-01T21:14:07.515607Z","end":"2026-03-01T21:14:07.934763Z","steps":["trace[507886586] 'range keys from in-memory index tree' (duration: 418.97571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:07.935125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.407186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:14:07.935268Z","caller":"traceutil/trace.go:171","msg":"trace[1265992691] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"804.307723ms","start":"2026-03-01T21:14:07.13093Z","end":"2026-03-01T21:14:07.935237Z","steps":["trace[1265992691] 'process raft request' (duration: 624.701502ms)","trace[1265992691] 'compare' (duration: 179.11057ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:07.935375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:07.130912Z","time spent":"804.408536ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:14:08.178379Z","caller":"traceutil/trace.go:171","msg":"trace[1578523853] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1609; }","duration":"891.318989ms","start":"2026-03-01T21:14:07.287026Z","end":"2026-03-01T21:14:08.178344Z","steps":["trace[1578523853] 'read index received' (duration: 468.631974ms)","trace[1578523853] 'applied index is now lower than readState.Index' (duration: 422.666234ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:08.178692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.377761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-01T21:14:08.178741Z","caller":"traceutil/trace.go:171","msg":"trace[156064640] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1565; }","duration":"829.470833ms","start":"2026-03-01T21:14:07.349255Z","end":"2026-03-01T21:14:08.178725Z","steps":["trace[156064640] 'agreement among raft nodes before linearized reading' (duration: 829.274049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.178752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.370407ms","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-03-01T21:14:08.178781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:07.349243Z","time spent":"829.528034ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-01T21:14:08.178829Z","caller":"traceutil/trace.go:171","msg":"trace[1961526261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1565; }","duration":"219.5313ms","start":"2026-03-01T21:14:07.95928Z","end":"2026-03-01T21:14:08.178811Z","steps":["trace[1961526261] 'agreement among raft nodes before linearized reading' (duration: 219.310285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.178839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.939794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41fa4e9d-445f-400b-9280-56ae7396ddb1\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-01T21:14:08.178852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.664791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-03-01T21:14:08.178932Z","caller":"traceutil/trace.go:171","msg":"trace[1767740006] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41fa4e9d-445f-400b-9280-56ae7396ddb1; range_end:; response_count:1; response_revision:1565; }","duration":"421.097707ms","start":"2026-03-01T21:14:07.757813Z","end":"2026-03-01T21:14:08.178911Z","steps":["trace[1767740006] 'agreement among raft nodes before linearized reading' (duration: 420.806921ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:08.178948Z","caller":"traceutil/trace.go:171","msg":"trace[2088124218] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1565; }","duration":"778.796475ms","start":"2026-03-01T21:14:07.400126Z","end":"2026-03-01T21:14:08.178923Z","steps":["trace[2088124218] 'agreement among raft nodes before linearized reading' (duration: 778.513138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.178988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:07.757786Z","time spent":"421.19297ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41fa4e9d-445f-400b-9280-56ae7396ddb1\" "} {"level":"warn","ts":"2026-03-01T21:14:08.178995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:07.40011Z","time spent":"778.875366ms","remote":"127.0.0.1:34448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-01T21:14:08.178752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.704827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9033"} {"level":"info","ts":"2026-03-01T21:14:08.179092Z","caller":"traceutil/trace.go:171","msg":"trace[1152419213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1565; }","duration":"892.060375ms","start":"2026-03-01T21:14:07.287003Z","end":"2026-03-01T21:14:08.179063Z","steps":["trace[1152419213] 'agreement among raft nodes before linearized reading' (duration: 891.463941ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:08.178505Z","caller":"traceutil/trace.go:171","msg":"trace[993283951] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"1.045456702s","start":"2026-03-01T21:14:07.132988Z","end":"2026-03-01T21:14:08.178445Z","steps":["trace[993283951] 'process raft request' (duration: 1.034299175s)","trace[993283951] 'compare' (duration: 10.809449ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:08.179205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:07.286993Z","time spent":"892.175688ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":9056,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-03-01T21:14:08.17952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:07.132971Z","time spent":"1.046258901s","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:14:08.721662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.10898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:14:08.721907Z","caller":"traceutil/trace.go:171","msg":"trace[867991487] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"538.244698ms","start":"2026-03-01T21:14:08.183627Z","end":"2026-03-01T21:14:08.721871Z","steps":["trace[867991487] 'process raft request' (duration: 217.825441ms)","trace[867991487] 'compare' (duration: 319.964517ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:14:08.721946Z","caller":"traceutil/trace.go:171","msg":"trace[2076928100] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"535.66797ms","start":"2026-03-01T21:14:08.186258Z","end":"2026-03-01T21:14:08.721926Z","steps":["trace[2076928100] 'process raft request' (duration: 535.525396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.722022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.186234Z","time spent":"535.751931ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:14:08.722044Z","caller":"traceutil/trace.go:171","msg":"trace[526151691] linearizableReadLoop","detail":"{readStateIndex:1614; appliedIndex:1611; }","duration":"433.120846ms","start":"2026-03-01T21:14:08.288899Z","end":"2026-03-01T21:14:08.72202Z","steps":["trace[526151691] 'read index received' (duration: 112.536344ms)","trace[526151691] 'applied index is now lower than readState.Index' (duration: 320.583342ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:08.722077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.1836Z","time spent":"538.378842ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:14:08.722164Z","caller":"traceutil/trace.go:171","msg":"trace[831368121] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"531.726649ms","start":"2026-03-01T21:14:08.190423Z","end":"2026-03-01T21:14:08.72215Z","steps":["trace[831368121] 'process raft request' (duration: 531.428502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.722271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.190404Z","time spent":"531.793061ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9335,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:14:08.722446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.088444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:14:08.722475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.531719ms","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-03-01T21:14:08.722502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.547489ms","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-03-01T21:14:08.722514Z","caller":"traceutil/trace.go:171","msg":"trace[1324529515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1568; }","duration":"431.59174ms","start":"2026-03-01T21:14:08.290909Z","end":"2026-03-01T21:14:08.722501Z","steps":["trace[1324529515] 'agreement among raft nodes before linearized reading' (duration: 431.445176ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:08.722518Z","caller":"traceutil/trace.go:171","msg":"trace[1037005665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1568; }","duration":"187.224357ms","start":"2026-03-01T21:14:08.535272Z","end":"2026-03-01T21:14:08.722496Z","steps":["trace[1037005665] 'agreement among raft nodes before linearized reading' (duration: 187.089183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.722547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.290891Z","time spent":"431.649942ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T21:14:08.722559Z","caller":"traceutil/trace.go:171","msg":"trace[470560027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1568; }","duration":"431.61417ms","start":"2026-03-01T21:14:08.29092Z","end":"2026-03-01T21:14:08.722534Z","steps":["trace[470560027] 'agreement among raft nodes before linearized reading' (duration: 431.479487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.722578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.15778ms","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-03-01T21:14:08.722595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.290908Z","time spent":"431.679932ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T21:14:08.7226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.683748ms","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-03-01T21:14:08.722637Z","caller":"traceutil/trace.go:171","msg":"trace[708819504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1568; }","duration":"431.236472ms","start":"2026-03-01T21:14:08.291381Z","end":"2026-03-01T21:14:08.722618Z","steps":["trace[708819504] 'agreement among raft nodes before linearized reading' (duration: 430.993607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.722681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.291372Z","time spent":"431.297824ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-01T21:14:08.722684Z","caller":"traceutil/trace.go:171","msg":"trace[1733817986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1568; }","duration":"433.809331ms","start":"2026-03-01T21:14:08.288855Z","end":"2026-03-01T21:14:08.722664Z","steps":["trace[1733817986] 'agreement among raft nodes before linearized reading' (duration: 433.552436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:08.722732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:08.288838Z","time spent":"433.883333ms","remote":"127.0.0.1:43472","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-03-01T21:14:10.960351Z","caller":"traceutil/trace.go:171","msg":"trace[1262368130] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1635; }","duration":"219.307385ms","start":"2026-03-01T21:14:10.741024Z","end":"2026-03-01T21:14:10.960331Z","steps":["trace[1262368130] 'read index received' (duration: 174.262447ms)","trace[1262368130] 'applied index is now lower than readState.Index' (duration: 45.043788ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:14:10.960361Z","caller":"traceutil/trace.go:171","msg":"trace[670013632] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"221.072687ms","start":"2026-03-01T21:14:10.739254Z","end":"2026-03-01T21:14:10.960326Z","steps":["trace[670013632] 'process raft request' (duration: 175.837845ms)","trace[670013632] 'compare' (duration: 45.093419ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:10.960763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.406358ms","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-03-01T21:14:10.960809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.222304ms","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-03-01T21:14:10.960828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.400578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-01T21:14:10.960877Z","caller":"traceutil/trace.go:171","msg":"trace[1284371411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1591; }","duration":"219.567842ms","start":"2026-03-01T21:14:10.741285Z","end":"2026-03-01T21:14:10.960853Z","steps":["trace[1284371411] 'agreement among raft nodes before linearized reading' (duration: 219.252925ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:10.960885Z","caller":"traceutil/trace.go:171","msg":"trace[1982534047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1591; }","duration":"219.314917ms","start":"2026-03-01T21:14:10.741552Z","end":"2026-03-01T21:14:10.960867Z","steps":["trace[1982534047] 'agreement among raft nodes before linearized reading' (duration: 219.077601ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:10.960929Z","caller":"traceutil/trace.go:171","msg":"trace[2088772362] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1591; }","duration":"219.52846ms","start":"2026-03-01T21:14:10.741376Z","end":"2026-03-01T21:14:10.960905Z","steps":["trace[2088772362] 'agreement among raft nodes before linearized reading' (duration: 219.310635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:10.960935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.8941ms","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-03-01T21:14:10.961004Z","caller":"traceutil/trace.go:171","msg":"trace[673119524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1591; }","duration":"219.977181ms","start":"2026-03-01T21:14:10.740992Z","end":"2026-03-01T21:14:10.960969Z","steps":["trace[673119524] 'agreement among raft nodes before linearized reading' (duration: 219.417138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:14.448458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.216349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10949"} {"level":"info","ts":"2026-03-01T21:14:14.448551Z","caller":"traceutil/trace.go:171","msg":"trace[2108544403] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1637; }","duration":"111.354453ms","start":"2026-03-01T21:14:14.337177Z","end":"2026-03-01T21:14:14.448532Z","steps":["trace[2108544403] 'range keys from in-memory index tree' (duration: 111.009355ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:14.584189Z","caller":"traceutil/trace.go:171","msg":"trace[1328749914] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"124.118219ms","start":"2026-03-01T21:14:14.460049Z","end":"2026-03-01T21:14:14.584167Z","steps":["trace[1328749914] 'process raft request' (duration: 123.827592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:14.912129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.814582ms","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-03-01T21:14:14.912359Z","caller":"traceutil/trace.go:171","msg":"trace[1042357466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1642; }","duration":"165.921883ms","start":"2026-03-01T21:14:14.746253Z","end":"2026-03-01T21:14:14.912174Z","steps":["trace[1042357466] 'range keys from in-memory index tree' (duration: 165.633237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.268282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-01T21:14:17.3462Z","caller":"traceutil/trace.go:171","msg":"trace[698397606] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1658; }","duration":"331.386155ms","start":"2026-03-01T21:14:17.014794Z","end":"2026-03-01T21:14:17.34618Z","steps":["trace[698397606] 'range keys from in-memory index tree' (duration: 331.088548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.34618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.697554ms","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-03-01T21:14:17.346235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:17.014779Z","time spent":"331.449496ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-01T21:14:17.346197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.82397ms","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-03-01T21:14:17.3463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.785066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-03-01T21:14:17.346364Z","caller":"traceutil/trace.go:171","msg":"trace[822138576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1658; }","duration":"358.000074ms","start":"2026-03-01T21:14:16.988309Z","end":"2026-03-01T21:14:17.346309Z","steps":["trace[822138576] 'range keys from in-memory index tree' (duration: 357.606215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.903791ms","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-03-01T21:14:17.346413Z","caller":"traceutil/trace.go:171","msg":"trace[179343215] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1658; }","duration":"100.940611ms","start":"2026-03-01T21:14:17.245448Z","end":"2026-03-01T21:14:17.346388Z","steps":["trace[179343215] 'range keys from in-memory index tree' (duration: 100.628811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:16.988279Z","time spent":"358.145958ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-01T21:14:17.346449Z","caller":"traceutil/trace.go:171","msg":"trace[1888806998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1658; }","duration":"358.063996ms","start":"2026-03-01T21:14:16.988365Z","end":"2026-03-01T21:14:17.346429Z","steps":["trace[1888806998] 'range keys from in-memory index tree' (duration: 357.675876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.233107ms","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-03-01T21:14:17.346263Z","caller":"traceutil/trace.go:171","msg":"trace[1702942435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1658; }","duration":"420.852147ms","start":"2026-03-01T21:14:16.925391Z","end":"2026-03-01T21:14:17.346243Z","steps":["trace[1702942435] 'range keys from in-memory index tree' (duration: 420.416297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:16.925363Z","time spent":"421.195627ms","remote":"127.0.0.1:43472","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-03-01T21:14:17.346499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:16.988334Z","time spent":"358.155558ms","remote":"127.0.0.1:43472","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-03-01T21:14:17.346628Z","caller":"traceutil/trace.go:171","msg":"trace[737765835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1658; }","duration":"357.333319ms","start":"2026-03-01T21:14:16.989152Z","end":"2026-03-01T21:14:17.346485Z","steps":["trace[737765835] 'range keys from in-memory index tree' (duration: 357.045763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.07803ms","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-03-01T21:14:17.346894Z","caller":"traceutil/trace.go:171","msg":"trace[812394930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1658; }","duration":"362.232564ms","start":"2026-03-01T21:14:16.984634Z","end":"2026-03-01T21:14:17.346867Z","steps":["trace[812394930] 'range keys from in-memory index tree' (duration: 361.792403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.346948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:16.984623Z","time spent":"362.313335ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T21:14:17.34692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:16.989132Z","time spent":"357.77085ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T21:14:50.811716Z","caller":"traceutil/trace.go:171","msg":"trace[517631656] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"109.780007ms","start":"2026-03-01T21:14:50.701912Z","end":"2026-03-01T21:14:50.811692Z","steps":["trace[517631656] 'process raft request' (duration: 109.638813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:51.501214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.994772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:14:51.501384Z","caller":"traceutil/trace.go:171","msg":"trace[1434122073] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"461.720169ms","start":"2026-03-01T21:14:51.03964Z","end":"2026-03-01T21:14:51.501361Z","steps":["trace[1434122073] 'read index received' (duration: 52.366349ms)","trace[1434122073] 'applied index is now lower than readState.Index' (duration: 409.35206ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:14:51.501639Z","caller":"traceutil/trace.go:171","msg":"trace[333542643] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"681.990698ms","start":"2026-03-01T21:14:50.819625Z","end":"2026-03-01T21:14:51.501615Z","steps":["trace[333542643] 'process raft request' (duration: 272.469044ms)","trace[333542643] 'compare' (duration: 408.879539ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:51.501752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:50.819599Z","time spent":"682.093591ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:14:51.50181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.16865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.1898d41c37b7d507\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-01T21:14:51.501857Z","caller":"traceutil/trace.go:171","msg":"trace[116733301] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.1898d41c37b7d507; range_end:; response_count:1; response_revision:1882; }","duration":"462.265592ms","start":"2026-03-01T21:14:51.039576Z","end":"2026-03-01T21:14:51.501841Z","steps":["trace[116733301] 'agreement among raft nodes before linearized reading' (duration: 461.853233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:51.501904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.039542Z","time spent":"462.353554ms","remote":"127.0.0.1:43284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.1898d41c37b7d507\" "} {"level":"warn","ts":"2026-03-01T21:14:51.640208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.765232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-01T21:14:51.645043Z","caller":"traceutil/trace.go:171","msg":"trace[756318922] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"438.385875ms","start":"2026-03-01T21:14:51.206627Z","end":"2026-03-01T21:14:51.645013Z","steps":["trace[756318922] 'process raft request' (duration: 438.084648ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:51.645211Z","caller":"traceutil/trace.go:171","msg":"trace[1369961358] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1946; }","duration":"110.600674ms","start":"2026-03-01T21:14:51.534574Z","end":"2026-03-01T21:14:51.645174Z","steps":["trace[1369961358] 'read index received' (duration: 110.099062ms)","trace[1369961358] 'applied index is now lower than readState.Index' (duration: 500.132µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:14:51.645337Z","caller":"traceutil/trace.go:171","msg":"trace[26365567] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"140.629464ms","start":"2026-03-01T21:14:51.504639Z","end":"2026-03-01T21:14:51.645269Z","steps":["trace[26365567] 'process raft request' (duration: 140.299486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:51.645389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.811789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:14:51.645434Z","caller":"traceutil/trace.go:171","msg":"trace[695728100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1884; }","duration":"110.915702ms","start":"2026-03-01T21:14:51.534506Z","end":"2026-03-01T21:14:51.645421Z","steps":["trace[695728100] 'agreement among raft nodes before linearized reading' (duration: 110.809869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:51.645577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.723241ms","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-03-01T21:14:51.645626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.206572Z","time spent":"438.94087ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:14:51.645683Z","caller":"traceutil/trace.go:171","msg":"trace[1117056753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1884; }","duration":"105.862445ms","start":"2026-03-01T21:14:51.539797Z","end":"2026-03-01T21:14:51.645659Z","steps":["trace[1117056753] 'agreement among raft nodes before linearized reading' (duration: 105.605259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:52.183877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.451268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:14:52.184115Z","caller":"traceutil/trace.go:171","msg":"trace[513020042] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"532.719302ms","start":"2026-03-01T21:14:51.651345Z","end":"2026-03-01T21:14:52.184064Z","steps":["trace[513020042] 'process raft request' (duration: 96.977418ms)","trace[513020042] 'compare' (duration: 435.312595ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:14:52.184172Z","caller":"traceutil/trace.go:171","msg":"trace[366674697] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"532.343123ms","start":"2026-03-01T21:14:51.651798Z","end":"2026-03-01T21:14:52.184141Z","steps":["trace[366674697] 'process raft request' (duration: 532.250351ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:52.184227Z","caller":"traceutil/trace.go:171","msg":"trace[1551201344] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"532.072046ms","start":"2026-03-01T21:14:51.652126Z","end":"2026-03-01T21:14:52.184198Z","steps":["trace[1551201344] 'process raft request' (duration: 531.958414ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:52.18414Z","caller":"traceutil/trace.go:171","msg":"trace[893763072] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1949; }","duration":"489.582527ms","start":"2026-03-01T21:14:51.694543Z","end":"2026-03-01T21:14:52.184126Z","steps":["trace[893763072] 'read index received' (duration: 53.72145ms)","trace[893763072] 'applied index is now lower than readState.Index' (duration: 435.859787ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:52.184304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.757482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11670"} {"level":"warn","ts":"2026-03-01T21:14:52.184311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.651783Z","time spent":"532.457146ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:14:52.184348Z","caller":"traceutil/trace.go:171","msg":"trace[1956428560] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1889; }","duration":"489.821914ms","start":"2026-03-01T21:14:51.694513Z","end":"2026-03-01T21:14:52.184335Z","steps":["trace[1956428560] 'agreement among raft nodes before linearized reading' (duration: 489.660439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:52.184383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.652119Z","time spent":"532.17817ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:14:52.184389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.6945Z","time spent":"489.879965ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11693,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-01T21:14:52.184324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.651319Z","time spent":"532.881106ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:14:52.184616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.58116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-01T21:14:52.184715Z","caller":"traceutil/trace.go:171","msg":"trace[1016526102] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1889; }","duration":"450.741423ms","start":"2026-03-01T21:14:51.733934Z","end":"2026-03-01T21:14:52.184676Z","steps":["trace[1016526102] 'agreement among raft nodes before linearized reading' (duration: 450.475997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:52.184772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.733915Z","time spent":"450.847556ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-01T21:14:52.18427Z","caller":"traceutil/trace.go:171","msg":"trace[61699957] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"532.553568ms","start":"2026-03-01T21:14:51.65154Z","end":"2026-03-01T21:14:52.184093Z","steps":["trace[61699957] 'process raft request' (duration: 532.453556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:52.185052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:51.651529Z","time spent":"533.456931ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:15:59.020565Z","caller":"traceutil/trace.go:171","msg":"trace[908868244] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"390.475372ms","start":"2026-03-01T21:15:58.630059Z","end":"2026-03-01T21:15:59.020535Z","steps":["trace[908868244] 'process raft request' (duration: 390.274127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.020759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:58.630045Z","time spent":"390.615986ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:15:59.024638Z","caller":"traceutil/trace.go:171","msg":"trace[659579626] linearizableReadLoop","detail":"{readStateIndex:2454; appliedIndex:2452; }","duration":"389.936209ms","start":"2026-03-01T21:15:58.634673Z","end":"2026-03-01T21:15:59.02461Z","steps":["trace[659579626] 'read index received' (duration: 385.815389ms)","trace[659579626] 'applied index is now lower than readState.Index' (duration: 4.11919ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:15:59.024702Z","caller":"traceutil/trace.go:171","msg":"trace[34579221] transaction","detail":"{read_only:false; response_revision:2362; number_of_response:1; }","duration":"389.97562ms","start":"2026-03-01T21:15:58.634699Z","end":"2026-03-01T21:15:59.024675Z","steps":["trace[34579221] 'process raft request' (duration: 389.859317ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:59.024702Z","caller":"traceutil/trace.go:171","msg":"trace[304095909] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"392.060151ms","start":"2026-03-01T21:15:58.632592Z","end":"2026-03-01T21:15:59.024652Z","steps":["trace[304095909] 'process raft request' (duration: 391.798375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.024977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.266257ms","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-03-01T21:15:59.025043Z","caller":"traceutil/trace.go:171","msg":"trace[1044437864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2362; }","duration":"390.40385ms","start":"2026-03-01T21:15:58.634624Z","end":"2026-03-01T21:15:59.025028Z","steps":["trace[1044437864] 'agreement among raft nodes before linearized reading' (duration: 390.185635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.025059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:58.632568Z","time spent":"392.364488ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:15:59.025084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:58.634597Z","time spent":"390.476992ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T21:15:59.025067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:58.634687Z","time spent":"390.134044ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:15:59.025611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.494886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"warn","ts":"2026-03-01T21:15:59.02562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.848622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-01T21:15:59.025666Z","caller":"traceutil/trace.go:171","msg":"trace[1407244237] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2362; }","duration":"158.576957ms","start":"2026-03-01T21:15:58.867072Z","end":"2026-03-01T21:15:59.025649Z","steps":["trace[1407244237] 'agreement among raft nodes before linearized reading' (duration: 158.441314ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:59.025679Z","caller":"traceutil/trace.go:171","msg":"trace[613381383] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2362; }","duration":"390.953724ms","start":"2026-03-01T21:15:58.634707Z","end":"2026-03-01T21:15:59.025661Z","steps":["trace[613381383] 'agreement among raft nodes before linearized reading' (duration: 390.737449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.025731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:58.63467Z","time spent":"391.047177ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-03-01T21:15:59.025647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.78763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-03-01T21:15:59.025887Z","caller":"traceutil/trace.go:171","msg":"trace[1199652060] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2362; }","duration":"147.097447ms","start":"2026-03-01T21:15:58.878769Z","end":"2026-03-01T21:15:59.025866Z","steps":["trace[1199652060] 'agreement among raft nodes before linearized reading' (duration: 146.707568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.02588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.808613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-01T21:15:59.025968Z","caller":"traceutil/trace.go:171","msg":"trace[218575305] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2362; }","duration":"361.922916ms","start":"2026-03-01T21:15:58.664022Z","end":"2026-03-01T21:15:59.025945Z","steps":["trace[218575305] 'agreement among raft nodes before linearized reading' (duration: 361.585208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.026016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:58.66401Z","time spent":"361.997148ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-01T21:15:59.253081Z","caller":"traceutil/trace.go:171","msg":"trace[1615060961] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"222.441235ms","start":"2026-03-01T21:15:59.030605Z","end":"2026-03-01T21:15:59.253046Z","steps":["trace[1615060961] 'process raft request' (duration: 185.997087ms)","trace[1615060961] 'compare' (duration: 36.305415ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:15:59.746594Z","caller":"traceutil/trace.go:171","msg":"trace[876272241] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"715.961931ms","start":"2026-03-01T21:15:59.030605Z","end":"2026-03-01T21:15:59.746567Z","steps":["trace[876272241] 'process raft request' (duration: 715.765086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.746754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.030586Z","time spent":"716.086213ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:15:59.746964Z","caller":"traceutil/trace.go:171","msg":"trace[1095682040] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"716.248398ms","start":"2026-03-01T21:15:59.030692Z","end":"2026-03-01T21:15:59.74694Z","steps":["trace[1095682040] 'process raft request' (duration: 715.831518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.747123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.030683Z","time spent":"716.35645ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:15:59.747213Z","caller":"traceutil/trace.go:171","msg":"trace[1166607073] linearizableReadLoop","detail":"{readStateIndex:2458; appliedIndex:2455; }","duration":"716.484791ms","start":"2026-03-01T21:15:59.030702Z","end":"2026-03-01T21:15:59.747187Z","steps":["trace[1166607073] 'read index received' (duration: 185.905253ms)","trace[1166607073] 'applied index is now lower than readState.Index' (duration: 530.578598ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:15:59.747254Z","caller":"traceutil/trace.go:171","msg":"trace[909994631] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"634.536834ms","start":"2026-03-01T21:15:59.1127Z","end":"2026-03-01T21:15:59.747237Z","steps":["trace[909994631] 'process raft request' (duration: 634.40568ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:59.747274Z","caller":"traceutil/trace.go:171","msg":"trace[1614072083] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"714.012842ms","start":"2026-03-01T21:15:59.033246Z","end":"2026-03-01T21:15:59.747259Z","steps":["trace[1614072083] 'process raft request' (duration: 713.592482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.747335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.112681Z","time spent":"634.611866ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:15:59.747345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.033235Z","time spent":"714.071154ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:15:59.747413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.703749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"warn","ts":"2026-03-01T21:15:59.747441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.689476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:15:59.747448Z","caller":"traceutil/trace.go:171","msg":"trace[69568337] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2367; }","duration":"716.771729ms","start":"2026-03-01T21:15:59.030667Z","end":"2026-03-01T21:15:59.747439Z","steps":["trace[69568337] 'agreement among raft nodes before linearized reading' (duration: 716.635146ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:59.747465Z","caller":"traceutil/trace.go:171","msg":"trace[1907973447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2367; }","duration":"212.80005ms","start":"2026-03-01T21:15:59.534658Z","end":"2026-03-01T21:15:59.747458Z","steps":["trace[1907973447] 'agreement among raft nodes before linearized reading' (duration: 212.755869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.74748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.030647Z","time spent":"716.826391ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-01T21:15:59.747502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.687828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1247"} {"level":"warn","ts":"2026-03-01T21:15:59.747534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.176725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:15:59.747574Z","caller":"traceutil/trace.go:171","msg":"trace[562923436] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2367; }","duration":"714.791511ms","start":"2026-03-01T21:15:59.032766Z","end":"2026-03-01T21:15:59.747557Z","steps":["trace[562923436] 'agreement among raft nodes before linearized reading' (duration: 714.556906ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:59.747586Z","caller":"traceutil/trace.go:171","msg":"trace[277437074] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2367; }","duration":"488.263606ms","start":"2026-03-01T21:15:59.259299Z","end":"2026-03-01T21:15:59.747563Z","steps":["trace[277437074] 'agreement among raft nodes before linearized reading' (duration: 488.195415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:59.747618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.032753Z","time spent":"714.856183ms","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1270,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-03-01T21:15:59.747618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.259276Z","time spent":"488.333689ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:15:59.748064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.320853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-01T21:15:59.748143Z","caller":"traceutil/trace.go:171","msg":"trace[1480156908] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2367; }","duration":"213.489397ms","start":"2026-03-01T21:15:59.534635Z","end":"2026-03-01T21:15:59.748125Z","steps":["trace[1480156908] 'agreement among raft nodes before linearized reading' (duration: 213.233421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:16:00.427364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.732974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:16:00.427598Z","caller":"traceutil/trace.go:171","msg":"trace[572234048] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"672.08802ms","start":"2026-03-01T21:15:59.75548Z","end":"2026-03-01T21:16:00.427568Z","steps":["trace[572234048] 'process raft request' (duration: 281.994217ms)","trace[572234048] 'compare' (duration: 389.413476ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:16:00.427693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.75546Z","time spent":"672.190832ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:16:00.629732Z","caller":"traceutil/trace.go:171","msg":"trace[2136539621] linearizableReadLoop","detail":"{readStateIndex:2462; appliedIndex:2460; }","duration":"713.097909ms","start":"2026-03-01T21:15:59.916589Z","end":"2026-03-01T21:16:00.629687Z","steps":["trace[2136539621] 'read index received' (duration: 120.916919ms)","trace[2136539621] 'applied index is now lower than readState.Index' (duration: 592.17915ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:16:00.630043Z","caller":"traceutil/trace.go:171","msg":"trace[1018463014] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"833.036215ms","start":"2026-03-01T21:15:59.796988Z","end":"2026-03-01T21:16:00.630024Z","steps":["trace[1018463014] 'process raft request' (duration: 832.02091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:16:00.63024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.796944Z","time spent":"833.191118ms","remote":"127.0.0.1:43682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:16:00.630299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.688783ms","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-03-01T21:16:00.630436Z","caller":"traceutil/trace.go:171","msg":"trace[1455661790] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2369; }","duration":"713.892209ms","start":"2026-03-01T21:15:59.916523Z","end":"2026-03-01T21:16:00.630415Z","steps":["trace[1455661790] 'agreement among raft nodes before linearized reading' (duration: 713.694213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:16:00.630487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:59.916495Z","time spent":"713.980541ms","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":10,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:16:00.630796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.213318ms","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-03-01T21:16:00.630852Z","caller":"traceutil/trace.go:171","msg":"trace[1973964516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2369; }","duration":"584.33736ms","start":"2026-03-01T21:16:00.0465Z","end":"2026-03-01T21:16:00.630838Z","steps":["trace[1973964516] 'agreement among raft nodes before linearized reading' (duration: 584.154846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:16:00.630886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:16:00.046468Z","time spent":"584.410542ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T21:16:00.631022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.511687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.1898d42b264b80b4\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-03-01T21:16:00.631123Z","caller":"traceutil/trace.go:171","msg":"trace[182479387] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.1898d42b264b80b4; range_end:; response_count:1; response_revision:2369; }","duration":"466.66254ms","start":"2026-03-01T21:16:00.164434Z","end":"2026-03-01T21:16:00.631096Z","steps":["trace[182479387] 'agreement among raft nodes before linearized reading' (duration: 466.337492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:16:00.63118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:16:00.164409Z","time spent":"466.761743ms","remote":"127.0.0.1:43284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.1898d42b264b80b4\" "} {"level":"warn","ts":"2026-03-01T21:16:00.631678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.821701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-03-01T21:16:00.631733Z","caller":"traceutil/trace.go:171","msg":"trace[407636587] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2369; }","duration":"422.934394ms","start":"2026-03-01T21:16:00.208784Z","end":"2026-03-01T21:16:00.631718Z","steps":["trace[407636587] 'agreement among raft nodes before linearized reading' (duration: 421.90607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:16:00.631765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:16:00.20875Z","time spent":"423.007445ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-03-01T21:18:29.625057Z","caller":"traceutil/trace.go:171","msg":"trace[100574655] transaction","detail":"{read_only:false; response_revision:4156; number_of_response:1; }","duration":"123.779839ms","start":"2026-03-01T21:18:29.501245Z","end":"2026-03-01T21:18:29.625025Z","steps":["trace[100574655] 'process raft request' (duration: 123.576435ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:29.70474Z","caller":"traceutil/trace.go:171","msg":"trace[1412048393] linearizableReadLoop","detail":"{readStateIndex:4340; appliedIndex:4338; }","duration":"104.710515ms","start":"2026-03-01T21:18:29.600002Z","end":"2026-03-01T21:18:29.704713Z","steps":["trace[1412048393] 'read index received' (duration: 24.884659ms)","trace[1412048393] 'applied index is now lower than readState.Index' (duration: 79.824466ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:18:29.704898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.89717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:18:29.704874Z","caller":"traceutil/trace.go:171","msg":"trace[355938481] transaction","detail":"{read_only:false; response_revision:4157; number_of_response:1; }","duration":"179.382202ms","start":"2026-03-01T21:18:29.525446Z","end":"2026-03-01T21:18:29.704828Z","steps":["trace[355938481] 'process raft request' (duration: 179.110736ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:29.704939Z","caller":"traceutil/trace.go:171","msg":"trace[1528137820] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:4157; }","duration":"105.022202ms","start":"2026-03-01T21:18:29.599904Z","end":"2026-03-01T21:18:29.704927Z","steps":["trace[1528137820] 'agreement among raft nodes before linearized reading' (duration: 104.92803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.113209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.017108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:18:30.113347Z","caller":"traceutil/trace.go:171","msg":"trace[1325327636] transaction","detail":"{read_only:false; response_revision:4158; number_of_response:1; }","duration":"461.524345ms","start":"2026-03-01T21:18:29.651797Z","end":"2026-03-01T21:18:30.113322Z","steps":["trace[1325327636] 'process raft request' (duration: 342.301942ms)","trace[1325327636] 'compare' (duration: 118.511946ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:18:30.113425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.651765Z","time spent":"461.620667ms","remote":"127.0.0.1:42876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T21:18:30.19923Z","caller":"traceutil/trace.go:171","msg":"trace[1522284987] linearizableReadLoop","detail":"{readStateIndex:4342; appliedIndex:4340; }","duration":"480.637689ms","start":"2026-03-01T21:18:29.718566Z","end":"2026-03-01T21:18:30.199203Z","steps":["trace[1522284987] 'read index received' (duration: 275.54893ms)","trace[1522284987] 'applied index is now lower than readState.Index' (duration: 205.087699ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:30.199399Z","caller":"traceutil/trace.go:171","msg":"trace[2005256190] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4158; }","duration":"485.932532ms","start":"2026-03-01T21:18:29.713404Z","end":"2026-03-01T21:18:30.199337Z","steps":["trace[2005256190] 'process raft request' (duration: 485.716187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.199642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.713371Z","time spent":"486.170678ms","remote":"127.0.0.1:43682","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T21:18:30.199621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.035038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0dbb6dbd-0c94-4d3b-815c-ead98fd2269a\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-01T21:18:30.199806Z","caller":"traceutil/trace.go:171","msg":"trace[1557592889] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0dbb6dbd-0c94-4d3b-815c-ead98fd2269a; range_end:; response_count:1; response_revision:4158; }","duration":"481.259933ms","start":"2026-03-01T21:18:29.718524Z","end":"2026-03-01T21:18:30.199784Z","steps":["trace[1557592889] 'agreement among raft nodes before linearized reading' (duration: 480.887665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.199852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.718514Z","time spent":"481.330235ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0dbb6dbd-0c94-4d3b-815c-ead98fd2269a\" "} {"level":"info","ts":"2026-03-01T21:18:30.347995Z","caller":"traceutil/trace.go:171","msg":"trace[369271997] linearizableReadLoop","detail":"{readStateIndex:4343; appliedIndex:4342; }","duration":"148.522104ms","start":"2026-03-01T21:18:30.199414Z","end":"2026-03-01T21:18:30.347936Z","steps":["trace[369271997] 'read index received' (duration: 127.576957ms)","trace[369271997] 'applied index is now lower than readState.Index' (duration: 20.943617ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:30.348322Z","caller":"traceutil/trace.go:171","msg":"trace[803815269] transaction","detail":"{read_only:false; response_revision:4159; number_of_response:1; }","duration":"225.097265ms","start":"2026-03-01T21:18:30.123181Z","end":"2026-03-01T21:18:30.348278Z","steps":["trace[803815269] 'process raft request' (duration: 203.780849ms)","trace[803815269] 'compare' (duration: 20.658901ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:18:30.348425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.474302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4jht\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-01T21:18:30.348503Z","caller":"traceutil/trace.go:171","msg":"trace[1374468052] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4jht; range_end:; response_count:1; response_revision:4159; }","duration":"479.628545ms","start":"2026-03-01T21:18:29.86886Z","end":"2026-03-01T21:18:30.348489Z","steps":["trace[1374468052] 'agreement among raft nodes before linearized reading' (duration: 479.303947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.348577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.868818Z","time spent":"479.746958ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-v4jht\" "} {"level":"warn","ts":"2026-03-01T21:18:30.348612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.332338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-01T21:18:30.348725Z","caller":"traceutil/trace.go:171","msg":"trace[1708247120] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4159; }","duration":"166.496922ms","start":"2026-03-01T21:18:30.182198Z","end":"2026-03-01T21:18:30.348695Z","steps":["trace[1708247120] 'agreement among raft nodes before linearized reading' (duration: 166.049421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.348746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.60784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-01T21:18:30.348794Z","caller":"traceutil/trace.go:171","msg":"trace[1859292608] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4159; }","duration":"309.671791ms","start":"2026-03-01T21:18:30.039105Z","end":"2026-03-01T21:18:30.348777Z","steps":["trace[1859292608] 'agreement among raft nodes before linearized reading' (duration: 309.571719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.3488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.445855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0dbb6dbd-0c94-4d3b-815c-ead98fd2269a\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-01T21:18:30.348838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:30.039086Z","time spent":"309.742373ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-03-01T21:18:30.348858Z","caller":"traceutil/trace.go:171","msg":"trace[1953740655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0dbb6dbd-0c94-4d3b-815c-ead98fd2269a; range_end:; response_count:1; response_revision:4159; }","duration":"275.579009ms","start":"2026-03-01T21:18:30.073262Z","end":"2026-03-01T21:18:30.348841Z","steps":["trace[1953740655] 'agreement among raft nodes before linearized reading' (duration: 275.351874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.348611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.601161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781340"} {"level":"info","ts":"2026-03-01T21:18:30.348992Z","caller":"traceutil/trace.go:171","msg":"trace[330248757] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4159; }","duration":"479.010701ms","start":"2026-03-01T21:18:29.869969Z","end":"2026-03-01T21:18:30.34898Z","steps":["trace[330248757] 'agreement among raft nodes before linearized reading' (duration: 477.994818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.34902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.472889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-03-01T21:18:30.349034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.869933Z","time spent":"479.089142ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":781363,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-03-01T21:18:30.349049Z","caller":"traceutil/trace.go:171","msg":"trace[1332702179] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4159; }","duration":"497.53384ms","start":"2026-03-01T21:18:29.851506Z","end":"2026-03-01T21:18:30.34904Z","steps":["trace[1332702179] 'agreement among raft nodes before linearized reading' (duration: 497.440288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.34908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.851488Z","time spent":"497.584082ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-01T21:18:30.349134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.936154ms","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-03-01T21:18:30.349682Z","caller":"traceutil/trace.go:171","msg":"trace[1487942870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4159; }","duration":"481.496497ms","start":"2026-03-01T21:18:29.868143Z","end":"2026-03-01T21:18:30.349639Z","steps":["trace[1487942870] 'agreement among raft nodes before linearized reading' (duration: 480.918134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.349757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.868108Z","time spent":"481.63011ms","remote":"127.0.0.1:43472","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":"warn","ts":"2026-03-01T21:18:30.349301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.9893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-01T21:18:30.350008Z","caller":"traceutil/trace.go:171","msg":"trace[719110121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4159; }","duration":"482.744378ms","start":"2026-03-01T21:18:29.86725Z","end":"2026-03-01T21:18:30.349994Z","steps":["trace[719110121] 'agreement among raft nodes before linearized reading' (duration: 481.97566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.350064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.867222Z","time spent":"482.8285ms","remote":"127.0.0.1:43472","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-03-01T21:18:30.349005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.929485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-01T21:18:30.350244Z","caller":"traceutil/trace.go:171","msg":"trace[1922222702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4159; }","duration":"482.211035ms","start":"2026-03-01T21:18:29.868022Z","end":"2026-03-01T21:18:30.350233Z","steps":["trace[1922222702] 'agreement among raft nodes before linearized reading' (duration: 480.765491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.350429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:29.868008Z","time spent":"482.258986ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T21:18:30.35956Z","caller":"traceutil/trace.go:171","msg":"trace[461431249] transaction","detail":"{read_only:false; response_revision:4160; number_of_response:1; }","duration":"151.879342ms","start":"2026-03-01T21:18:30.207668Z","end":"2026-03-01T21:18:30.359547Z","steps":["trace[461431249] 'process raft request' (duration: 151.79291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:30.359754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.818924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-03-01T21:18:30.359835Z","caller":"traceutil/trace.go:171","msg":"trace[1879312961] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:4160; }","duration":"158.958817ms","start":"2026-03-01T21:18:30.200859Z","end":"2026-03-01T21:18:30.359817Z","steps":["trace[1879312961] 'agreement among raft nodes before linearized reading' (duration: 158.703321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:32.119605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.190011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:18:32.11974Z","caller":"traceutil/trace.go:171","msg":"trace[625546093] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4207; }","duration":"102.397796ms","start":"2026-03-01T21:18:32.01731Z","end":"2026-03-01T21:18:32.119708Z","steps":["trace[625546093] 'count revisions from in-memory index tree' (duration: 102.075788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:35.793191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.959136ms","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-03-01T21:18:35.793332Z","caller":"traceutil/trace.go:171","msg":"trace[466803555] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4292; }","duration":"199.157391ms","start":"2026-03-01T21:18:35.594143Z","end":"2026-03-01T21:18:35.7933Z","steps":["trace[466803555] 'count revisions from in-memory index tree' (duration: 198.855845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:35.793327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.163812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"warn","ts":"2026-03-01T21:18:35.793626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.097772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-01T21:18:35.79367Z","caller":"traceutil/trace.go:171","msg":"trace[2116598461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4293; }","duration":"133.209995ms","start":"2026-03-01T21:18:35.660449Z","end":"2026-03-01T21:18:35.793659Z","steps":["trace[2116598461] 'agreement among raft nodes before linearized reading' (duration: 132.974829ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:35.793374Z","caller":"traceutil/trace.go:171","msg":"trace[1109980845] linearizableReadLoop","detail":"{readStateIndex:4481; appliedIndex:4480; }","duration":"132.824846ms","start":"2026-03-01T21:18:35.660517Z","end":"2026-03-01T21:18:35.793341Z","steps":["trace[1109980845] 'read index received' (duration: 130.314898ms)","trace[1109980845] 'applied index is now lower than readState.Index' (duration: 2.508478ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:35.793462Z","caller":"traceutil/trace.go:171","msg":"trace[1278838198] transaction","detail":"{read_only:false; response_revision:4293; number_of_response:1; }","duration":"181.208933ms","start":"2026-03-01T21:18:35.612175Z","end":"2026-03-01T21:18:35.793384Z","steps":["trace[1278838198] 'process raft request' (duration: 178.139832ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:35.794427Z","caller":"traceutil/trace.go:171","msg":"trace[2107795938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4292; }","duration":"184.483ms","start":"2026-03-01T21:18:35.609099Z","end":"2026-03-01T21:18:35.793582Z","steps":["trace[2107795938] 'range keys from in-memory index tree' (duration: 184.011928ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:36.10919Z","caller":"traceutil/trace.go:171","msg":"trace[1052051562] linearizableReadLoop","detail":"{readStateIndex:4484; appliedIndex:4483; }","duration":"217.15218ms","start":"2026-03-01T21:18:35.892003Z","end":"2026-03-01T21:18:36.109155Z","steps":["trace[1052051562] 'read index received' (duration: 216.050665ms)","trace[1052051562] 'applied index is now lower than readState.Index' (duration: 1.100215ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:36.109274Z","caller":"traceutil/trace.go:171","msg":"trace[937338820] transaction","detail":"{read_only:false; response_revision:4296; number_of_response:1; }","duration":"246.944265ms","start":"2026-03-01T21:18:35.862298Z","end":"2026-03-01T21:18:36.109243Z","steps":["trace[937338820] 'process raft request' (duration: 245.802089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:36.109477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.455868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-01T21:18:36.109541Z","caller":"traceutil/trace.go:171","msg":"trace[318797931] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:4296; }","duration":"217.557391ms","start":"2026-03-01T21:18:35.891968Z","end":"2026-03-01T21:18:36.109525Z","steps":["trace[318797931] 'agreement among raft nodes before linearized reading' (duration: 217.297024ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:41.350184Z","caller":"traceutil/trace.go:171","msg":"trace[76564194] transaction","detail":"{read_only:false; response_revision:4351; number_of_response:1; }","duration":"117.59059ms","start":"2026-03-01T21:18:41.232555Z","end":"2026-03-01T21:18:41.350145Z","steps":["trace[76564194] 'process raft request' (duration: 117.336704ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.472384Z","caller":"traceutil/trace.go:171","msg":"trace[1597986143] transaction","detail":"{read_only:false; response_revision:4394; number_of_response:1; }","duration":"125.914985ms","start":"2026-03-01T21:18:42.346405Z","end":"2026-03-01T21:18:42.47232Z","steps":["trace[1597986143] 'process raft request' (duration: 125.548766ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.696079Z","caller":"traceutil/trace.go:171","msg":"trace[1580239043] linearizableReadLoop","detail":"{readStateIndex:4588; appliedIndex:4586; }","duration":"266.425419ms","start":"2026-03-01T21:18:42.429631Z","end":"2026-03-01T21:18:42.696057Z","steps":["trace[1580239043] 'read index received' (duration: 42.138002ms)","trace[1580239043] 'applied index is now lower than readState.Index' (duration: 224.286317ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:42.696321Z","caller":"traceutil/trace.go:171","msg":"trace[768079422] transaction","detail":"{read_only:false; response_revision:4395; number_of_response:1; }","duration":"278.282556ms","start":"2026-03-01T21:18:42.417991Z","end":"2026-03-01T21:18:42.696274Z","steps":["trace[768079422] 'process raft request' (duration: 277.851066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.696723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.069613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-01T21:18:42.696891Z","caller":"traceutil/trace.go:171","msg":"trace[819993042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4395; }","duration":"267.276468ms","start":"2026-03-01T21:18:42.429594Z","end":"2026-03-01T21:18:42.69687Z","steps":["trace[819993042] 'agreement among raft nodes before linearized reading' (duration: 266.741526ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.766016Z","caller":"traceutil/trace.go:171","msg":"trace[1107597784] transaction","detail":"{read_only:false; response_revision:4396; number_of_response:1; }","duration":"287.578462ms","start":"2026-03-01T21:18:42.478387Z","end":"2026-03-01T21:18:42.765965Z","steps":["trace[1107597784] 'process raft request' (duration: 287.354447ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.766448Z","caller":"traceutil/trace.go:171","msg":"trace[953780661] transaction","detail":"{read_only:false; response_revision:4397; number_of_response:1; }","duration":"287.558661ms","start":"2026-03-01T21:18:42.478866Z","end":"2026-03-01T21:18:42.766424Z","steps":["trace[953780661] 'process raft request' (duration: 287.033039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.766574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.547289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:18:42.766629Z","caller":"traceutil/trace.go:171","msg":"trace[291763947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4398; }","duration":"232.641941ms","start":"2026-03-01T21:18:42.533974Z","end":"2026-03-01T21:18:42.766616Z","steps":["trace[291763947] 'agreement among raft nodes before linearized reading' (duration: 232.492797ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.766821Z","caller":"traceutil/trace.go:171","msg":"trace[832148803] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"285.290858ms","start":"2026-03-01T21:18:42.481514Z","end":"2026-03-01T21:18:42.766805Z","steps":["trace[832148803] 'process raft request' (duration: 284.677104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.76703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.875041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-01T21:18:42.767084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.528891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-03-01T21:18:42.767089Z","caller":"traceutil/trace.go:171","msg":"trace[813115712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4398; }","duration":"333.959402ms","start":"2026-03-01T21:18:42.433115Z","end":"2026-03-01T21:18:42.767075Z","steps":["trace[813115712] 'agreement among raft nodes before linearized reading' (duration: 333.797098ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.767121Z","caller":"traceutil/trace.go:171","msg":"trace[1920462284] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4398; }","duration":"230.605493ms","start":"2026-03-01T21:18:42.536503Z","end":"2026-03-01T21:18:42.767108Z","steps":["trace[1920462284] 'agreement among raft nodes before linearized reading' (duration: 230.446609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.767117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.00238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-01T21:18:42.767232Z","caller":"traceutil/trace.go:171","msg":"trace[199035295] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4398; }","duration":"330.171004ms","start":"2026-03-01T21:18:42.437046Z","end":"2026-03-01T21:18:42.767217Z","steps":["trace[199035295] 'agreement among raft nodes before linearized reading' (duration: 329.820446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.767289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.066188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4jht\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-01T21:18:42.767347Z","caller":"traceutil/trace.go:171","msg":"trace[1994325012] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4jht; range_end:; response_count:1; response_revision:4398; }","duration":"329.17695ms","start":"2026-03-01T21:18:42.438162Z","end":"2026-03-01T21:18:42.767339Z","steps":["trace[1994325012] 'agreement among raft nodes before linearized reading' (duration: 329.059678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.767384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:42.438127Z","time spent":"329.248332ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-v4jht\" "} {"level":"warn","ts":"2026-03-01T21:18:42.767389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:42.437034Z","time spent":"330.290967ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-03-01T21:18:42.767617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.985683ms","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-03-01T21:18:42.767152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:42.433107Z","time spent":"334.034274ms","remote":"127.0.0.1:43472","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-03-01T21:18:42.767723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.024292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-01T21:18:42.767817Z","caller":"traceutil/trace.go:171","msg":"trace[364954077] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4398; }","duration":"243.147246ms","start":"2026-03-01T21:18:42.524647Z","end":"2026-03-01T21:18:42.767794Z","steps":["trace[364954077] 'agreement among raft nodes before linearized reading' (duration: 241.838996ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.767692Z","caller":"traceutil/trace.go:171","msg":"trace[175402808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4398; }","duration":"334.096875ms","start":"2026-03-01T21:18:42.433575Z","end":"2026-03-01T21:18:42.767672Z","steps":["trace[175402808] 'agreement among raft nodes before linearized reading' (duration: 333.311027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.767997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:42.43356Z","time spent":"334.423333ms","remote":"127.0.0.1:43472","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-03-01T21:18:42.888336Z","caller":"traceutil/trace.go:171","msg":"trace[1982104430] linearizableReadLoop","detail":"{readStateIndex:4593; appliedIndex:4591; }","duration":"121.86833ms","start":"2026-03-01T21:18:42.766454Z","end":"2026-03-01T21:18:42.888322Z","steps":["trace[1982104430] 'read index received' (duration: 46.055183ms)","trace[1982104430] 'applied index is now lower than readState.Index' (duration: 75.812317ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:42.888337Z","caller":"traceutil/trace.go:171","msg":"trace[187278329] transaction","detail":"{read_only:false; response_revision:4399; number_of_response:1; }","duration":"189.71797ms","start":"2026-03-01T21:18:42.698588Z","end":"2026-03-01T21:18:42.888306Z","steps":["trace[187278329] 'process raft request' (duration: 113.902903ms)","trace[187278329] 'compare' (duration: 75.600072ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:42.88837Z","caller":"traceutil/trace.go:171","msg":"trace[882613018] transaction","detail":"{read_only:false; response_revision:4400; number_of_response:1; }","duration":"186.25405ms","start":"2026-03-01T21:18:42.70208Z","end":"2026-03-01T21:18:42.888334Z","steps":["trace[882613018] 'process raft request' (duration: 186.157468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.88855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.091833ms","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-03-01T21:18:42.888581Z","caller":"traceutil/trace.go:171","msg":"trace[1185694563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4401; }","duration":"188.158854ms","start":"2026-03-01T21:18:42.700414Z","end":"2026-03-01T21:18:42.888573Z","steps":["trace[1185694563] 'agreement among raft nodes before linearized reading' (duration: 188.001341ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.88856Z","caller":"traceutil/trace.go:171","msg":"trace[1928693221] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"115.963743ms","start":"2026-03-01T21:18:42.772576Z","end":"2026-03-01T21:18:42.88854Z","steps":["trace[1928693221] 'process raft request' (duration: 115.719147ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.88884Z","caller":"traceutil/trace.go:171","msg":"trace[632296569] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"116.239859ms","start":"2026-03-01T21:18:42.772589Z","end":"2026-03-01T21:18:42.888829Z","steps":["trace[632296569] 'process raft request' (duration: 115.87564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.888839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.464324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:18:42.888866Z","caller":"traceutil/trace.go:171","msg":"trace[240699230] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"116.033285ms","start":"2026-03-01T21:18:42.772823Z","end":"2026-03-01T21:18:42.888856Z","steps":["trace[240699230] 'process raft request' (duration: 115.8637ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:42.88888Z","caller":"traceutil/trace.go:171","msg":"trace[1174415754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4403; }","duration":"119.533106ms","start":"2026-03-01T21:18:42.769335Z","end":"2026-03-01T21:18:42.888868Z","steps":["trace[1174415754] 'agreement among raft nodes before linearized reading' (duration: 119.449064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.888957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.193855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-03-01T21:18:42.888972Z","caller":"traceutil/trace.go:171","msg":"trace[2061484696] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4403; }","duration":"118.264586ms","start":"2026-03-01T21:18:42.770702Z","end":"2026-03-01T21:18:42.888967Z","steps":["trace[2061484696] 'agreement among raft nodes before linearized reading' (duration: 118.212635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.888971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.231832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-03-01T21:18:42.889008Z","caller":"traceutil/trace.go:171","msg":"trace[960277275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4403; }","duration":"117.300294ms","start":"2026-03-01T21:18:42.771695Z","end":"2026-03-01T21:18:42.888995Z","steps":["trace[960277275] 'agreement among raft nodes before linearized reading' (duration: 117.169811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:42.889263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.763951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:50398"} {"level":"info","ts":"2026-03-01T21:18:42.889338Z","caller":"traceutil/trace.go:171","msg":"trace[931460081] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4403; }","duration":"116.859804ms","start":"2026-03-01T21:18:42.77246Z","end":"2026-03-01T21:18:42.88932Z","steps":["trace[931460081] 'agreement among raft nodes before linearized reading' (duration: 116.458304ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:44.125734Z","caller":"traceutil/trace.go:171","msg":"trace[115245866] transaction","detail":"{read_only:false; response_revision:4411; number_of_response:1; }","duration":"279.295759ms","start":"2026-03-01T21:18:43.846413Z","end":"2026-03-01T21:18:44.125708Z","steps":["trace[115245866] 'process raft request' (duration: 279.126215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:51.442703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830177123457694,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T21:18:51.605369Z","caller":"traceutil/trace.go:171","msg":"trace[953424100] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"666.461531ms","start":"2026-03-01T21:18:50.938883Z","end":"2026-03-01T21:18:51.605345Z","steps":["trace[953424100] 'process raft request' (duration: 665.679933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:51.605543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:50.938862Z","time spent":"666.595364ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:18:52.44004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.255456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-01T21:18:52.440192Z","caller":"traceutil/trace.go:171","msg":"trace[968199848] linearizableReadLoop","detail":"{readStateIndex:4761; appliedIndex:4759; }","duration":"1.49755269s","start":"2026-03-01T21:18:50.94261Z","end":"2026-03-01T21:18:52.440163Z","steps":["trace[968199848] 'read index received' (duration: 660.773189ms)","trace[968199848] 'applied index is now lower than readState.Index' (duration: 836.777191ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:18:52.440532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.969206ms","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":"warn","ts":"2026-03-01T21:18:52.440591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49584949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-01T21:18:52.440639Z","caller":"traceutil/trace.go:171","msg":"trace[1983959788] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:4562; }","duration":"792.12829ms","start":"2026-03-01T21:18:51.648485Z","end":"2026-03-01T21:18:52.440613Z","steps":["trace[1983959788] 'agreement among raft nodes before linearized reading' (duration: 791.937106ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:52.440689Z","caller":"traceutil/trace.go:171","msg":"trace[13200654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4562; }","duration":"1.495980222s","start":"2026-03-01T21:18:50.944685Z","end":"2026-03-01T21:18:52.440665Z","steps":["trace[13200654] 'agreement among raft nodes before linearized reading' (duration: 1.495685696s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.440693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.648465Z","time spent":"792.217763ms","remote":"127.0.0.1:43682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":7,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:18:52.440725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322413737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-01T21:18:52.440801Z","caller":"traceutil/trace.go:171","msg":"trace[1807240937] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4562; }","duration":"1.32251424s","start":"2026-03-01T21:18:51.118265Z","end":"2026-03-01T21:18:52.440779Z","steps":["trace[1807240937] 'agreement among raft nodes before linearized reading' (duration: 1.322276834s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.440768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.957855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"warn","ts":"2026-03-01T21:18:52.440843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.118248Z","time spent":"1.322586481s","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-01T21:18:52.440929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464509518s","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-03-01T21:18:52.440932Z","caller":"traceutil/trace.go:171","msg":"trace[757033362] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4562; }","duration":"586.16288ms","start":"2026-03-01T21:18:51.854703Z","end":"2026-03-01T21:18:52.440866Z","steps":["trace[757033362] 'agreement among raft nodes before linearized reading' (duration: 585.709089ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:52.440981Z","caller":"traceutil/trace.go:171","msg":"trace[712605076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4562; }","duration":"1.46457892s","start":"2026-03-01T21:18:50.976384Z","end":"2026-03-01T21:18:52.440963Z","steps":["trace[712605076] 'agreement among raft nodes before linearized reading' (duration: 1.464471358s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:50.976371Z","time spent":"1.464637391s","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T21:18:52.441029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.854676Z","time spent":"586.335774ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2125,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-03-01T21:18:52.441007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304829237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:18:52.441081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019087378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a68e55c9-12f8-41b4-9c88-1c3a6fc99366\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-03-01T21:18:52.441088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476584671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-01T21:18:52.440903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43709392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a68e55c9-12f8-41b4-9c88-1c3a6fc99366\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-01T21:18:52.441205Z","caller":"traceutil/trace.go:171","msg":"trace[1969146223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4562; }","duration":"1.476711833s","start":"2026-03-01T21:18:50.964463Z","end":"2026-03-01T21:18:52.441175Z","steps":["trace[1969146223] 'agreement among raft nodes before linearized reading' (duration: 1.476496128s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492503751s","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-03-01T21:18:52.441262Z","caller":"traceutil/trace.go:171","msg":"trace[1455807092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4562; }","duration":"1.492567572s","start":"2026-03-01T21:18:50.948683Z","end":"2026-03-01T21:18:52.441251Z","steps":["trace[1455807092] 'agreement among raft nodes before linearized reading' (duration: 1.492355097s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.350921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:18:52.441306Z","caller":"traceutil/trace.go:171","msg":"trace[1877258868] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a68e55c9-12f8-41b4-9c88-1c3a6fc99366; range_end:; response_count:1; response_revision:4562; }","duration":"1.019294453s","start":"2026-03-01T21:18:51.421881Z","end":"2026-03-01T21:18:52.441175Z","steps":["trace[1877258868] 'agreement among raft nodes before linearized reading' (duration: 1.018875623s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:50.948674Z","time spent":"1.492629634s","remote":"127.0.0.1:43472","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-03-01T21:18:52.441323Z","caller":"traceutil/trace.go:171","msg":"trace[1195073244] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4562; }","duration":"915.437083ms","start":"2026-03-01T21:18:51.525876Z","end":"2026-03-01T21:18:52.441313Z","steps":["trace[1195073244] 'agreement among raft nodes before linearized reading' (duration: 915.360291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.44133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324001214s","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":"warn","ts":"2026-03-01T21:18:52.4413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49803178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:59703"} {"level":"warn","ts":"2026-03-01T21:18:52.441354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39076023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-03-01T21:18:52.441384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.921463ms","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-03-01T21:18:52.441405Z","caller":"traceutil/trace.go:171","msg":"trace[1213768049] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4562; }","duration":"1.324106577s","start":"2026-03-01T21:18:51.117275Z","end":"2026-03-01T21:18:52.441382Z","steps":["trace[1213768049] 'agreement among raft nodes before linearized reading' (duration: 1.32381721s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406760424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-03-01T21:18:52.441503Z","caller":"traceutil/trace.go:171","msg":"trace[1465638665] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4562; }","duration":"1.406884017s","start":"2026-03-01T21:18:51.034605Z","end":"2026-03-01T21:18:52.441489Z","steps":["trace[1465638665] 'agreement among raft nodes before linearized reading' (duration: 1.406766033s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.034592Z","time spent":"1.406935938s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-01T21:18:52.441502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.117255Z","time spent":"1.32423157s","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-01T21:18:52.441211Z","caller":"traceutil/trace.go:171","msg":"trace[62016347] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a68e55c9-12f8-41b4-9c88-1c3a6fc99366; range_end:; response_count:1; response_revision:4562; }","duration":"1.437442858s","start":"2026-03-01T21:18:51.003746Z","end":"2026-03-01T21:18:52.441188Z","steps":["trace[62016347] 'agreement among raft nodes before linearized reading' (duration: 1.436885915s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.003733Z","time spent":"1.437867329s","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a68e55c9-12f8-41b4-9c88-1c3a6fc99366\" "} {"level":"info","ts":"2026-03-01T21:18:52.441421Z","caller":"traceutil/trace.go:171","msg":"trace[609852158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4562; }","duration":"277.029426ms","start":"2026-03-01T21:18:52.164382Z","end":"2026-03-01T21:18:52.441412Z","steps":["trace[609852158] 'agreement among raft nodes before linearized reading' (duration: 276.926513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.441267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:50.96445Z","time spent":"1.476806516s","remote":"127.0.0.1:43472","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-03-01T21:18:52.441342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.421855Z","time spent":"1.019478498s","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a68e55c9-12f8-41b4-9c88-1c3a6fc99366\" "} {"level":"info","ts":"2026-03-01T21:18:52.44113Z","caller":"traceutil/trace.go:171","msg":"trace[273772114] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4562; }","duration":"1.30499191s","start":"2026-03-01T21:18:51.136114Z","end":"2026-03-01T21:18:52.441106Z","steps":["trace[273772114] 'agreement among raft nodes before linearized reading' (duration: 1.304778395s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.442191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.136102Z","time spent":"1.306063997s","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:18:52.440826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:50.944668Z","time spent":"1.496058595s","remote":"127.0.0.1:43472","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-03-01T21:18:52.441365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.525846Z","time spent":"915.510934ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-03-01T21:18:52.441427Z","caller":"traceutil/trace.go:171","msg":"trace[1687399671] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4562; }","duration":"1.49883096s","start":"2026-03-01T21:18:50.94257Z","end":"2026-03-01T21:18:52.441401Z","steps":["trace[1687399671] 'agreement among raft nodes before linearized reading' (duration: 1.497788875s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.442539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:50.942545Z","time spent":"1.499975766s","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":59726,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-01T21:18:52.441417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.084036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:18:52.44267Z","caller":"traceutil/trace.go:171","msg":"trace[1616899810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4562; }","duration":"907.365826ms","start":"2026-03-01T21:18:51.535277Z","end":"2026-03-01T21:18:52.442643Z","steps":["trace[1616899810] 'agreement among raft nodes before linearized reading' (duration: 906.028554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.442722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.535266Z","time spent":"907.444697ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-01T21:18:52.44143Z","caller":"traceutil/trace.go:171","msg":"trace[441118562] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4562; }","duration":"1.390839613s","start":"2026-03-01T21:18:51.050569Z","end":"2026-03-01T21:18:52.441409Z","steps":["trace[441118562] 'agreement among raft nodes before linearized reading' (duration: 1.390694269s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.442825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:51.050551Z","time spent":"1.392258015s","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-03-01T21:18:52.845977Z","caller":"traceutil/trace.go:171","msg":"trace[1135638256] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"397.442532ms","start":"2026-03-01T21:18:52.448499Z","end":"2026-03-01T21:18:52.845941Z","steps":["trace[1135638256] 'process raft request' (duration: 397.33979ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:52.846012Z","caller":"traceutil/trace.go:171","msg":"trace[1765201121] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"397.487143ms","start":"2026-03-01T21:18:52.448479Z","end":"2026-03-01T21:18:52.845966Z","steps":["trace[1765201121] 'process raft request' (duration: 397.139715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.846197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.448477Z","time spent":"397.603235ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:18:52.8462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.448468Z","time spent":"397.625257ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:18:52.846268Z","caller":"traceutil/trace.go:171","msg":"trace[1542238310] linearizableReadLoop","detail":"{readStateIndex:4767; appliedIndex:4762; }","duration":"389.699971ms","start":"2026-03-01T21:18:52.456547Z","end":"2026-03-01T21:18:52.846247Z","steps":["trace[1542238310] 'read index received' (duration: 389.137138ms)","trace[1542238310] 'applied index is now lower than readState.Index' (duration: 562.063µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:52.846295Z","caller":"traceutil/trace.go:171","msg":"trace[1431772958] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"397.661987ms","start":"2026-03-01T21:18:52.448582Z","end":"2026-03-01T21:18:52.846244Z","steps":["trace[1431772958] 'process raft request' (duration: 397.307689ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:52.846412Z","caller":"traceutil/trace.go:171","msg":"trace[2098654547] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"397.071144ms","start":"2026-03-01T21:18:52.449327Z","end":"2026-03-01T21:18:52.846398Z","steps":["trace[2098654547] 'process raft request' (duration: 396.774617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.84647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.449317Z","time spent":"397.121245ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:18:52.84652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.035969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4796"} {"level":"warn","ts":"2026-03-01T21:18:52.846518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.983138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-01T21:18:52.84661Z","caller":"traceutil/trace.go:171","msg":"trace[2074980300] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4568; }","duration":"396.130031ms","start":"2026-03-01T21:18:52.450431Z","end":"2026-03-01T21:18:52.846561Z","steps":["trace[2074980300] 'agreement among raft nodes before linearized reading' (duration: 395.948837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.846659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.450401Z","time spent":"396.247013ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":4819,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-03-01T21:18:52.846644Z","caller":"traceutil/trace.go:171","msg":"trace[773440712] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4568; }","duration":"399.165782ms","start":"2026-03-01T21:18:52.447465Z","end":"2026-03-01T21:18:52.846631Z","steps":["trace[773440712] 'agreement among raft nodes before linearized reading' (duration: 398.929097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.846565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.448575Z","time spent":"397.855701ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:18:52.846785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.147061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-03-01T21:18:52.846915Z","caller":"traceutil/trace.go:171","msg":"trace[1289197458] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4568; }","duration":"399.301465ms","start":"2026-03-01T21:18:52.447574Z","end":"2026-03-01T21:18:52.846876Z","steps":["trace[1289197458] 'agreement among raft nodes before linearized reading' (duration: 398.924626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.846802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.447443Z","time spent":"399.350446ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-01T21:18:52.84697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.447569Z","time spent":"399.389397ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-03-01T21:18:52.847189Z","caller":"traceutil/trace.go:171","msg":"trace[1947519279] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"396.979741ms","start":"2026-03-01T21:18:52.45016Z","end":"2026-03-01T21:18:52.847139Z","steps":["trace[1947519279] 'process raft request' (duration: 396.038139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.847413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.450149Z","time spent":"397.158475ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:18:52.85425Z","caller":"traceutil/trace.go:171","msg":"trace[1714334711] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"393.634384ms","start":"2026-03-01T21:18:52.460602Z","end":"2026-03-01T21:18:52.854237Z","steps":["trace[1714334711] 'process raft request' (duration: 393.402998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:18:52.854325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:18:52.460582Z","time spent":"393.706126ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:18:53.004501Z","caller":"traceutil/trace.go:171","msg":"trace[1280587116] linearizableReadLoop","detail":"{readStateIndex:4770; appliedIndex:4769; }","duration":"145.691976ms","start":"2026-03-01T21:18:52.858784Z","end":"2026-03-01T21:18:53.004476Z","steps":["trace[1280587116] 'read index received' (duration: 100.122033ms)","trace[1280587116] 'applied index is now lower than readState.Index' (duration: 45.568623ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:18:53.004633Z","caller":"traceutil/trace.go:171","msg":"trace[1129837860] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"152.630598ms","start":"2026-03-01T21:18:52.851964Z","end":"2026-03-01T21:18:53.004595Z","steps":["trace[1129837860] 'process raft request' (duration: 106.899651ms)","trace[1129837860] 'compare' (duration: 45.465119ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:18:53.004735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.936441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-03-01T21:18:53.004982Z","caller":"traceutil/trace.go:171","msg":"trace[1560649231] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4571; }","duration":"146.203608ms","start":"2026-03-01T21:18:52.858743Z","end":"2026-03-01T21:18:53.004946Z","steps":["trace[1560649231] 'agreement among raft nodes before linearized reading' (duration: 145.822379ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:53.007483Z","caller":"traceutil/trace.go:171","msg":"trace[686212232] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"107.075796ms","start":"2026-03-01T21:18:52.900361Z","end":"2026-03-01T21:18:53.007437Z","steps":["trace[686212232] 'process raft request' (duration: 106.88193ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:54.651386Z","caller":"traceutil/trace.go:171","msg":"trace[2012894857] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"158.295528ms","start":"2026-03-01T21:18:54.493064Z","end":"2026-03-01T21:18:54.651359Z","steps":["trace[2012894857] 'process raft request' (duration: 151.562072ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:59.899146Z","caller":"traceutil/trace.go:171","msg":"trace[2098261735] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4873; }","duration":"179.323198ms","start":"2026-03-01T21:18:59.719797Z","end":"2026-03-01T21:18:59.89912Z","steps":["trace[2098261735] 'read index received' (duration: 177.599008ms)","trace[2098261735] 'applied index is now lower than readState.Index' (duration: 1.72277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:18:59.899355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.537863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-03-01T21:18:59.899397Z","caller":"traceutil/trace.go:171","msg":"trace[216319871] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4671; }","duration":"179.637066ms","start":"2026-03-01T21:18:59.719748Z","end":"2026-03-01T21:18:59.899385Z","steps":["trace[216319871] 'agreement among raft nodes before linearized reading' (duration: 179.458311ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:59.89945Z","caller":"traceutil/trace.go:171","msg":"trace[741898358] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"229.611041ms","start":"2026-03-01T21:18:59.669785Z","end":"2026-03-01T21:18:59.899396Z","steps":["trace[741898358] 'process raft request' (duration: 227.591993ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.241933Z","caller":"traceutil/trace.go:171","msg":"trace[465104554] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"121.04275ms","start":"2026-03-01T21:19:03.120856Z","end":"2026-03-01T21:19:03.241898Z","steps":["trace[465104554] 'process raft request' (duration: 120.733623ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.344511Z","caller":"traceutil/trace.go:171","msg":"trace[1778394379] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4895; }","duration":"213.495346ms","start":"2026-03-01T21:19:03.130986Z","end":"2026-03-01T21:19:03.344481Z","steps":["trace[1778394379] 'read index received' (duration: 110.583778ms)","trace[1778394379] 'applied index is now lower than readState.Index' (duration: 102.909978ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:03.34459Z","caller":"traceutil/trace.go:171","msg":"trace[825098222] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"223.55357ms","start":"2026-03-01T21:19:03.121001Z","end":"2026-03-01T21:19:03.344554Z","steps":["trace[825098222] 'process raft request' (duration: 184.571651ms)","trace[825098222] 'compare' (duration: 38.689071ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:03.344604Z","caller":"traceutil/trace.go:171","msg":"trace[244580875] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"111.218542ms","start":"2026-03-01T21:19:03.233352Z","end":"2026-03-01T21:19:03.34457Z","steps":["trace[244580875] 'process raft request' (duration: 111.064229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:03.344795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.741062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:19:03.344865Z","caller":"traceutil/trace.go:171","msg":"trace[1240608365] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4694; }","duration":"213.878545ms","start":"2026-03-01T21:19:03.130961Z","end":"2026-03-01T21:19:03.344839Z","steps":["trace[1240608365] 'agreement among raft nodes before linearized reading' (duration: 213.692461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:03.346147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.527843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-03-01T21:19:03.346267Z","caller":"traceutil/trace.go:171","msg":"trace[2013176124] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4694; }","duration":"100.717007ms","start":"2026-03-01T21:19:03.245518Z","end":"2026-03-01T21:19:03.346235Z","steps":["trace[2013176124] 'agreement among raft nodes before linearized reading' (duration: 99.745825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:03.346782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.968415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-01T21:19:03.346845Z","caller":"traceutil/trace.go:171","msg":"trace[933412996] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4694; }","duration":"123.080018ms","start":"2026-03-01T21:19:03.223753Z","end":"2026-03-01T21:19:03.346833Z","steps":["trace[933412996] 'agreement among raft nodes before linearized reading' (duration: 121.4356ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.678521Z","caller":"traceutil/trace.go:171","msg":"trace[1663931665] linearizableReadLoop","detail":"{readStateIndex:4902; appliedIndex:4901; }","duration":"143.31608ms","start":"2026-03-01T21:19:03.535174Z","end":"2026-03-01T21:19:03.67849Z","steps":["trace[1663931665] 'read index received' (duration: 90.765945ms)","trace[1663931665] 'applied index is now lower than readState.Index' (duration: 52.548904ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:03.678589Z","caller":"traceutil/trace.go:171","msg":"trace[1134688680] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4697; }","duration":"164.656657ms","start":"2026-03-01T21:19:03.513904Z","end":"2026-03-01T21:19:03.67856Z","steps":["trace[1134688680] 'process raft request' (duration: 112.075252ms)","trace[1134688680] 'compare' (duration: 52.451742ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:19:03.678724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.534985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:03.678767Z","caller":"traceutil/trace.go:171","msg":"trace[269842041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4697; }","duration":"143.655638ms","start":"2026-03-01T21:19:03.5351Z","end":"2026-03-01T21:19:03.678755Z","steps":["trace[269842041] 'agreement among raft nodes before linearized reading' (duration: 143.555895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:03.67914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.701711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-03-01T21:19:03.679238Z","caller":"traceutil/trace.go:171","msg":"trace[1075477717] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4697; }","duration":"113.861954ms","start":"2026-03-01T21:19:03.56535Z","end":"2026-03-01T21:19:03.679212Z","steps":["trace[1075477717] 'agreement among raft nodes before linearized reading' (duration: 113.354452ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.918883Z","caller":"traceutil/trace.go:171","msg":"trace[2135503884] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"228.384632ms","start":"2026-03-01T21:19:03.690465Z","end":"2026-03-01T21:19:03.918849Z","steps":["trace[2135503884] 'process raft request' (duration: 228.091334ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.995848Z","caller":"traceutil/trace.go:171","msg":"trace[1615361017] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"213.26252ms","start":"2026-03-01T21:19:03.78255Z","end":"2026-03-01T21:19:03.995812Z","steps":["trace[1615361017] 'process raft request' (duration: 186.61401ms)","trace[1615361017] 'compare' (duration: 26.372614ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:03.995981Z","caller":"traceutil/trace.go:171","msg":"trace[360678804] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"213.201058ms","start":"2026-03-01T21:19:03.782736Z","end":"2026-03-01T21:19:03.995937Z","steps":["trace[360678804] 'process raft request' (duration: 212.946832ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.996168Z","caller":"traceutil/trace.go:171","msg":"trace[134241858] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"212.130504ms","start":"2026-03-01T21:19:03.784021Z","end":"2026-03-01T21:19:03.996152Z","steps":["trace[134241858] 'process raft request' (duration: 211.808037ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.995905Z","caller":"traceutil/trace.go:171","msg":"trace[274340086] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"212.698407ms","start":"2026-03-01T21:19:03.783181Z","end":"2026-03-01T21:19:03.995879Z","steps":["trace[274340086] 'process raft request' (duration: 212.582564ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:03.996426Z","caller":"traceutil/trace.go:171","msg":"trace[1597101627] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"124.056911ms","start":"2026-03-01T21:19:03.872319Z","end":"2026-03-01T21:19:03.996376Z","steps":["trace[1597101627] 'process raft request' (duration: 123.743214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:04.342963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.645735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:19:04.34312Z","caller":"traceutil/trace.go:171","msg":"trace[1093351201] linearizableReadLoop","detail":"{readStateIndex:4910; appliedIndex:4909; }","duration":"334.096685ms","start":"2026-03-01T21:19:04.008979Z","end":"2026-03-01T21:19:04.343076Z","steps":["trace[1093351201] 'read index received' (duration: 227.212804ms)","trace[1093351201] 'applied index is now lower than readState.Index' (duration: 106.882231ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:19:04.34334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.355192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-03-01T21:19:04.343378Z","caller":"traceutil/trace.go:171","msg":"trace[1731677819] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4705; }","duration":"334.392983ms","start":"2026-03-01T21:19:04.008973Z","end":"2026-03-01T21:19:04.343366Z","steps":["trace[1731677819] 'agreement among raft nodes before linearized reading' (duration: 334.188578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:04.343418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:19:04.008943Z","time spent":"334.465984ms","remote":"127.0.0.1:34448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4853,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-01T21:19:04.34372Z","caller":"traceutil/trace.go:171","msg":"trace[1373141003] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"342.335448ms","start":"2026-03-01T21:19:04.001298Z","end":"2026-03-01T21:19:04.343634Z","steps":["trace[1373141003] 'process raft request' (duration: 234.884824ms)","trace[1373141003] 'compare' (duration: 106.375109ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:19:04.344219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:19:04.001272Z","time spent":"342.798328ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:19:40.474818Z","caller":"traceutil/trace.go:171","msg":"trace[1530093723] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5059; }","duration":"140.853889ms","start":"2026-03-01T21:19:40.333932Z","end":"2026-03-01T21:19:40.474786Z","steps":["trace[1530093723] 'process raft request' (duration: 118.789127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:40.793235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.594138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-03-01T21:19:40.793342Z","caller":"traceutil/trace.go:171","msg":"trace[1809092664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5061; }","duration":"204.71971ms","start":"2026-03-01T21:19:40.588594Z","end":"2026-03-01T21:19:40.793314Z","steps":["trace[1809092664] 'range keys from in-memory index tree' (duration: 204.441202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:40.793619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.033409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:40.793759Z","caller":"traceutil/trace.go:171","msg":"trace[1695735553] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5061; }","duration":"211.216513ms","start":"2026-03-01T21:19:40.582515Z","end":"2026-03-01T21:19:40.793732Z","steps":["trace[1695735553] 'count revisions from in-memory index tree' (duration: 210.969948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:40.793891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.160451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3522"} {"level":"info","ts":"2026-03-01T21:19:40.793961Z","caller":"traceutil/trace.go:171","msg":"trace[1818580426] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5061; }","duration":"197.274254ms","start":"2026-03-01T21:19:40.596667Z","end":"2026-03-01T21:19:40.793941Z","steps":["trace[1818580426] 'range keys from in-memory index tree' (duration: 196.939267ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:40.930389Z","caller":"traceutil/trace.go:171","msg":"trace[1775388100] linearizableReadLoop","detail":"{readStateIndex:5283; appliedIndex:5282; }","duration":"130.77477ms","start":"2026-03-01T21:19:40.799586Z","end":"2026-03-01T21:19:40.930361Z","steps":["trace[1775388100] 'read index received' (duration: 130.557335ms)","trace[1775388100] 'applied index is now lower than readState.Index' (duration: 214.955µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:40.930419Z","caller":"traceutil/trace.go:171","msg":"trace[846133767] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"131.538559ms","start":"2026-03-01T21:19:40.798843Z","end":"2026-03-01T21:19:40.930382Z","steps":["trace[846133767] 'process raft request' (duration: 131.233172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:40.931266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.663923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:10 size:81267"} {"level":"info","ts":"2026-03-01T21:19:40.931334Z","caller":"traceutil/trace.go:171","msg":"trace[1821083535] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:10; response_revision:5062; }","duration":"131.766885ms","start":"2026-03-01T21:19:40.799548Z","end":"2026-03-01T21:19:40.931315Z","steps":["trace[1821083535] 'agreement among raft nodes before linearized reading' (duration: 130.936915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:40.931396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.387706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3522"} {"level":"info","ts":"2026-03-01T21:19:40.931493Z","caller":"traceutil/trace.go:171","msg":"trace[831672657] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5062; }","duration":"131.54997ms","start":"2026-03-01T21:19:40.799923Z","end":"2026-03-01T21:19:40.931473Z","steps":["trace[831672657] 'agreement among raft nodes before linearized reading' (duration: 130.66542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:41.16673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.025539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:19:41.167004Z","caller":"traceutil/trace.go:171","msg":"trace[1294730969] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"189.49681ms","start":"2026-03-01T21:19:40.977451Z","end":"2026-03-01T21:19:41.166948Z","steps":["trace[1294730969] 'process raft request' (duration: 74.180684ms)","trace[1294730969] 'compare' (duration: 114.868186ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:22:22.965853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3059} {"level":"info","ts":"2026-03-01T21:22:23.041567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3059,"took":"74.009435ms","hash":930122820,"current-db-size-bytes":43900928,"current-db-size":"44 MB","current-db-size-in-use-bytes":30281728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-01T21:22:23.041664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":930122820,"revision":3059,"compact-revision":-1} {"level":"info","ts":"2026-03-01T21:24:43.739622Z","caller":"traceutil/trace.go:171","msg":"trace[79226698] transaction","detail":"{read_only:false; response_revision:7581; number_of_response:1; }","duration":"104.901332ms","start":"2026-03-01T21:24:43.634691Z","end":"2026-03-01T21:24:43.739593Z","steps":["trace[79226698] 'process raft request' (duration: 104.693336ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:24:45.266365Z","caller":"traceutil/trace.go:171","msg":"trace[373025929] linearizableReadLoop","detail":"{readStateIndex:7939; appliedIndex:7938; }","duration":"373.953307ms","start":"2026-03-01T21:24:44.892363Z","end":"2026-03-01T21:24:45.266316Z","steps":["trace[373025929] 'read index received' (duration: 373.816264ms)","trace[373025929] 'applied index is now lower than readState.Index' (duration: 135.863µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:24:45.266447Z","caller":"traceutil/trace.go:171","msg":"trace[606429167] transaction","detail":"{read_only:false; response_revision:7586; number_of_response:1; }","duration":"375.209396ms","start":"2026-03-01T21:24:44.891181Z","end":"2026-03-01T21:24:45.26639Z","steps":["trace[606429167] 'process raft request' (duration: 374.978349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:24:45.266649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.468359ms","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-03-01T21:24:45.266651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.915116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18296"} {"level":"info","ts":"2026-03-01T21:24:45.266691Z","caller":"traceutil/trace.go:171","msg":"trace[1584435485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7586; }","duration":"371.5549ms","start":"2026-03-01T21:24:44.895123Z","end":"2026-03-01T21:24:45.266678Z","steps":["trace[1584435485] 'agreement among raft nodes before linearized reading' (duration: 371.388636ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:24:45.266706Z","caller":"traceutil/trace.go:171","msg":"trace[1398077019] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7586; }","duration":"112.031779ms","start":"2026-03-01T21:24:45.15466Z","end":"2026-03-01T21:24:45.266692Z","steps":["trace[1398077019] 'agreement among raft nodes before linearized reading' (duration: 111.868966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:24:45.266678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.045279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:24:45.266726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:24:44.895108Z","time spent":"371.610662ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T21:24:45.266714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.339505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-01T21:24:45.266716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:24:44.891144Z","time spent":"375.430061ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:24:45.266764Z","caller":"traceutil/trace.go:171","msg":"trace[1291492637] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:7586; }","duration":"308.149802ms","start":"2026-03-01T21:24:44.958596Z","end":"2026-03-01T21:24:45.266746Z","steps":["trace[1291492637] 'agreement among raft nodes before linearized reading' (duration: 307.998238ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:24:45.266785Z","caller":"traceutil/trace.go:171","msg":"trace[1599876423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7586; }","duration":"374.480399ms","start":"2026-03-01T21:24:44.892286Z","end":"2026-03-01T21:24:45.266766Z","steps":["trace[1599876423] 'agreement among raft nodes before linearized reading' (duration: 374.201193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:24:45.26681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:24:44.958502Z","time spent":"308.299005ms","remote":"127.0.0.1:43528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:24:45.266827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:24:44.892256Z","time spent":"374.563591ms","remote":"127.0.0.1:43472","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-03-01T21:24:45.266886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.864814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-01T21:24:45.266967Z","caller":"traceutil/trace.go:171","msg":"trace[14171968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7586; }","duration":"370.977967ms","start":"2026-03-01T21:24:44.89597Z","end":"2026-03-01T21:24:45.266948Z","steps":["trace[14171968] 'agreement among raft nodes before linearized reading' (duration: 370.642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:24:45.267003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:24:44.895961Z","time spent":"371.035349ms","remote":"127.0.0.1:43472","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-03-01T21:24:45.454721Z","caller":"traceutil/trace.go:171","msg":"trace[1111020682] transaction","detail":"{read_only:false; response_revision:7587; number_of_response:1; }","duration":"184.120662ms","start":"2026-03-01T21:24:45.270564Z","end":"2026-03-01T21:24:45.454685Z","steps":["trace[1111020682] 'process raft request' (duration: 183.971168ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:24:45.456155Z","caller":"traceutil/trace.go:171","msg":"trace[702959202] transaction","detail":"{read_only:false; response_revision:7588; number_of_response:1; }","duration":"184.698715ms","start":"2026-03-01T21:24:45.271433Z","end":"2026-03-01T21:24:45.456132Z","steps":["trace[702959202] 'process raft request' (duration: 184.457ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:24:45.456565Z","caller":"traceutil/trace.go:171","msg":"trace[2068991226] transaction","detail":"{read_only:false; response_revision:7589; number_of_response:1; }","duration":"183.143048ms","start":"2026-03-01T21:24:45.273403Z","end":"2026-03-01T21:24:45.456546Z","steps":["trace[2068991226] 'process raft request' (duration: 182.578765ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:24:57.121926Z","caller":"traceutil/trace.go:171","msg":"trace[701869143] transaction","detail":"{read_only:false; response_revision:7660; number_of_response:1; }","duration":"272.820984ms","start":"2026-03-01T21:24:56.849075Z","end":"2026-03-01T21:24:57.121896Z","steps":["trace[701869143] 'process raft request' (duration: 272.508607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:25:25.176517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.952005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4jht\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-01T21:25:25.176608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.342298ms","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-03-01T21:25:25.176626Z","caller":"traceutil/trace.go:171","msg":"trace[132944334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7884; }","duration":"384.396989ms","start":"2026-03-01T21:25:24.792222Z","end":"2026-03-01T21:25:25.176619Z","steps":["trace[132944334] 'range keys from in-memory index tree' (duration: 384.020441ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:25:25.176626Z","caller":"traceutil/trace.go:171","msg":"trace[671167163] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4jht; range_end:; response_count:1; response_revision:7884; }","duration":"176.110819ms","start":"2026-03-01T21:25:25.000497Z","end":"2026-03-01T21:25:25.176608Z","steps":["trace[671167163] 'range keys from in-memory index tree' (duration: 175.776821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:25:25.176649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:25:24.792186Z","time spent":"384.458691ms","remote":"127.0.0.1:43472","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":"warn","ts":"2026-03-01T21:25:25.176569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.007277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-03-01T21:25:25.1768Z","caller":"traceutil/trace.go:171","msg":"trace[236830594] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7884; }","duration":"327.269493ms","start":"2026-03-01T21:25:24.849521Z","end":"2026-03-01T21:25:25.17679Z","steps":["trace[236830594] 'range keys from in-memory index tree' (duration: 326.840932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:25:25.176831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:25:24.849503Z","time spent":"327.318855ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-01T21:25:38.236315Z","caller":"traceutil/trace.go:171","msg":"trace[1896861985] transaction","detail":"{read_only:false; response_revision:8012; number_of_response:1; }","duration":"116.099704ms","start":"2026-03-01T21:25:38.120185Z","end":"2026-03-01T21:25:38.236284Z","steps":["trace[1896861985] 'process raft request' (duration: 112.331996ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:25:39.33575Z","caller":"traceutil/trace.go:171","msg":"trace[788347856] transaction","detail":"{read_only:false; response_revision:8015; number_of_response:1; }","duration":"431.880761ms","start":"2026-03-01T21:25:38.903839Z","end":"2026-03-01T21:25:39.335719Z","steps":["trace[788347856] 'process raft request' (duration: 346.372021ms)","trace[788347856] 'compare' (duration: 85.366587ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:25:39.335958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:25:38.903824Z","time spent":"432.009463ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4597,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:25:39.381341Z","caller":"traceutil/trace.go:171","msg":"trace[829101762] transaction","detail":"{read_only:false; response_revision:8016; number_of_response:1; }","duration":"277.96777ms","start":"2026-03-01T21:25:39.103324Z","end":"2026-03-01T21:25:39.381292Z","steps":["trace[829101762] 'process raft request' (duration: 277.725825ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:25:40.324862Z","caller":"traceutil/trace.go:171","msg":"trace[786558400] transaction","detail":"{read_only:false; response_revision:8022; number_of_response:1; }","duration":"258.155567ms","start":"2026-03-01T21:25:40.066681Z","end":"2026-03-01T21:25:40.324836Z","steps":["trace[786558400] 'process raft request' (duration: 257.986313ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:25:40.505689Z","caller":"traceutil/trace.go:171","msg":"trace[1964144109] linearizableReadLoop","detail":"{readStateIndex:8401; appliedIndex:8400; }","duration":"176.544009ms","start":"2026-03-01T21:25:40.329113Z","end":"2026-03-01T21:25:40.505657Z","steps":["trace[1964144109] 'read index received' (duration: 119.237129ms)","trace[1964144109] 'applied index is now lower than readState.Index' (duration: 57.30532ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:25:40.505873Z","caller":"traceutil/trace.go:171","msg":"trace[1770867505] transaction","detail":"{read_only:false; response_revision:8023; number_of_response:1; }","duration":"438.643368ms","start":"2026-03-01T21:25:40.06715Z","end":"2026-03-01T21:25:40.505793Z","steps":["trace[1770867505] 'process raft request' (duration: 381.287927ms)","trace[1770867505] 'compare' (duration: 57.064484ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:25:40.506321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:25:40.067121Z","time spent":"438.859922ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:25:40.506426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.241005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-03-01T21:25:40.506537Z","caller":"traceutil/trace.go:171","msg":"trace[706098011] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8023; }","duration":"177.502731ms","start":"2026-03-01T21:25:40.329009Z","end":"2026-03-01T21:25:40.506512Z","steps":["trace[706098011] 'agreement among raft nodes before linearized reading' (duration: 176.812364ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:25:40.956086Z","caller":"traceutil/trace.go:171","msg":"trace[585281952] linearizableReadLoop","detail":"{readStateIndex:8406; appliedIndex:8405; }","duration":"139.967293ms","start":"2026-03-01T21:25:40.816063Z","end":"2026-03-01T21:25:40.95603Z","steps":["trace[585281952] 'read index received' (duration: 131.471844ms)","trace[585281952] 'applied index is now lower than readState.Index' (duration: 8.493919ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:25:40.956112Z","caller":"traceutil/trace.go:171","msg":"trace[152040142] transaction","detail":"{read_only:false; response_revision:8027; number_of_response:1; }","duration":"148.38398ms","start":"2026-03-01T21:25:40.807691Z","end":"2026-03-01T21:25:40.956075Z","steps":["trace[152040142] 'process raft request' (duration: 139.80811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:25:40.956345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.25965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-01T21:25:40.956392Z","caller":"traceutil/trace.go:171","msg":"trace[637314054] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8027; }","duration":"140.358332ms","start":"2026-03-01T21:25:40.816022Z","end":"2026-03-01T21:25:40.95638Z","steps":["trace[637314054] 'agreement among raft nodes before linearized reading' (duration: 140.176108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:25:41.021016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.070543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:25:41.021085Z","caller":"traceutil/trace.go:171","msg":"trace[1709677876] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8028; }","duration":"160.197286ms","start":"2026-03-01T21:25:40.860873Z","end":"2026-03-01T21:25:41.02107Z","steps":["trace[1709677876] 'agreement among raft nodes before linearized reading' (duration: 159.237054ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:26:38.273646Z","caller":"traceutil/trace.go:171","msg":"trace[1157370947] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"115.944261ms","start":"2026-03-01T21:26:38.157674Z","end":"2026-03-01T21:26:38.273618Z","steps":["trace[1157370947] 'process raft request' (duration: 115.652424ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:22.973368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6245} {"level":"info","ts":"2026-03-01T21:27:23.078143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6245,"took":"101.908389ms","hash":190076962,"current-db-size-bytes":47345664,"current-db-size":"47 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-01T21:27:23.078239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":190076962,"revision":6245,"compact-revision":3059} {"level":"info","ts":"2026-03-01T21:28:24.500693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-01T21:28:24.505407Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-01T21:28:24.5055Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-01T21:30:14.733298Z","caller":"traceutil/trace.go:171","msg":"trace[1296742601] transaction","detail":"{read_only:false; response_revision:10713; number_of_response:1; }","duration":"109.182103ms","start":"2026-03-01T21:30:14.62409Z","end":"2026-03-01T21:30:14.733272Z","steps":["trace[1296742601] 'process raft request' (duration: 109.02744ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:30:27.644385Z","caller":"traceutil/trace.go:171","msg":"trace[1559020463] transaction","detail":"{read_only:false; response_revision:10800; number_of_response:1; }","duration":"103.827479ms","start":"2026-03-01T21:30:27.540518Z","end":"2026-03-01T21:30:27.644345Z","steps":["trace[1559020463] 'process raft request' (duration: 72.610692ms)","trace[1559020463] 'compare' (duration: 30.537021ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:32:22.980208Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9010} {"level":"info","ts":"2026-03-01T21:32:23.068741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9010,"took":"85.39134ms","hash":2966162047,"current-db-size-bytes":47345664,"current-db-size":"47 MB","current-db-size-in-use-bytes":25477120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-01T21:32:23.068932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2966162047,"revision":9010,"compact-revision":6245} {"level":"info","ts":"2026-03-01T21:34:12.727459Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032f1.wal"} {"level":"info","ts":"2026-03-01T21:36:38.231513Z","caller":"traceutil/trace.go:171","msg":"trace[1773120764] linearizableReadLoop","detail":"{readStateIndex:14292; appliedIndex:14291; }","duration":"126.04036ms","start":"2026-03-01T21:36:38.105444Z","end":"2026-03-01T21:36:38.231485Z","steps":["trace[1773120764] 'read index received' (duration: 125.877036ms)","trace[1773120764] 'applied index is now lower than readState.Index' (duration: 162.394µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:36:38.231666Z","caller":"traceutil/trace.go:171","msg":"trace[186165029] transaction","detail":"{read_only:false; response_revision:13623; number_of_response:1; }","duration":"145.536346ms","start":"2026-03-01T21:36:38.086074Z","end":"2026-03-01T21:36:38.231611Z","steps":["trace[186165029] 'process raft request' (duration: 145.210359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:38.231753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.282946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-03-01T21:36:38.231826Z","caller":"traceutil/trace.go:171","msg":"trace[292546177] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13623; }","duration":"126.396838ms","start":"2026-03-01T21:36:38.105411Z","end":"2026-03-01T21:36:38.231808Z","steps":["trace[292546177] 'agreement among raft nodes before linearized reading' (duration: 126.207453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:38.390453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.210459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-03-01T21:36:38.390527Z","caller":"traceutil/trace.go:171","msg":"trace[1893176387] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13623; }","duration":"154.353042ms","start":"2026-03-01T21:36:38.236155Z","end":"2026-03-01T21:36:38.390508Z","steps":["trace[1893176387] 'range keys from in-memory index tree' (duration: 153.936292ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:36:38.390499Z","caller":"traceutil/trace.go:171","msg":"trace[154606146] transaction","detail":"{read_only:false; response_revision:13624; number_of_response:1; }","duration":"149.94495ms","start":"2026-03-01T21:36:38.240521Z","end":"2026-03-01T21:36:38.390466Z","steps":["trace[154606146] 'process raft request' (duration: 148.395023ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:36:49.794522Z","caller":"traceutil/trace.go:171","msg":"trace[1744293469] transaction","detail":"{read_only:false; response_revision:13714; number_of_response:1; }","duration":"107.868535ms","start":"2026-03-01T21:36:49.686613Z","end":"2026-03-01T21:36:49.794481Z","steps":["trace[1744293469] 'process raft request' (duration: 107.580269ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:36:54.273726Z","caller":"traceutil/trace.go:171","msg":"trace[1201156707] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13741; }","duration":"157.949507ms","start":"2026-03-01T21:36:54.115746Z","end":"2026-03-01T21:36:54.273696Z","steps":["trace[1201156707] 'process raft request' (duration: 140.192751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.362138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.407172ms","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":"warn","ts":"2026-03-01T21:36:55.362287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.227927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:36:55.362409Z","caller":"traceutil/trace.go:171","msg":"trace[1765880700] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:13746; }","duration":"589.858873ms","start":"2026-03-01T21:36:54.772369Z","end":"2026-03-01T21:36:55.362228Z","steps":["trace[1765880700] 'count revisions from in-memory index tree' (duration: 589.30139ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:36:55.362452Z","caller":"traceutil/trace.go:171","msg":"trace[2014440457] linearizableReadLoop","detail":"{readStateIndex:14424; appliedIndex:14423; }","duration":"468.886662ms","start":"2026-03-01T21:36:54.893544Z","end":"2026-03-01T21:36:55.36243Z","steps":["trace[2014440457] 'read index received' (duration: 97.39865ms)","trace[2014440457] 'applied index is now lower than readState.Index' (duration: 371.486802ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:36:55.362535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.994064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:36:55.362797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:54.772346Z","time spent":"590.432416ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-01T21:36:55.362577Z","caller":"traceutil/trace.go:171","msg":"trace[83867137] transaction","detail":"{read_only:false; response_revision:13747; number_of_response:1; }","duration":"512.244307ms","start":"2026-03-01T21:36:54.85032Z","end":"2026-03-01T21:36:55.362565Z","steps":["trace[83867137] 'process raft request' (duration: 140.617051ms)","trace[83867137] 'compare' (duration: 371.006502ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:36:55.362989Z","caller":"traceutil/trace.go:171","msg":"trace[160293010] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:13747; }","duration":"469.452515ms","start":"2026-03-01T21:36:54.893487Z","end":"2026-03-01T21:36:55.36294Z","steps":["trace[160293010] 'agreement among raft nodes before linearized reading' (duration: 469.001105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.363105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:54.893464Z","time spent":"469.603839ms","remote":"127.0.0.1:42824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:36:55.3632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:54.850293Z","time spent":"512.754949ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:36:55.363222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.900736ms","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-03-01T21:36:55.363274Z","caller":"traceutil/trace.go:171","msg":"trace[1218667470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13747; }","duration":"131.974479ms","start":"2026-03-01T21:36:55.231286Z","end":"2026-03-01T21:36:55.36326Z","steps":["trace[1218667470] 'agreement among raft nodes before linearized reading' (duration: 131.771664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.363245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.678535ms","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-03-01T21:36:55.363318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.791631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-01T21:36:55.363352Z","caller":"traceutil/trace.go:171","msg":"trace[461878865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13747; }","duration":"132.786168ms","start":"2026-03-01T21:36:55.230542Z","end":"2026-03-01T21:36:55.363329Z","steps":["trace[461878865] 'agreement among raft nodes before linearized reading' (duration: 132.506491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.363329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.390687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v4jht\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-01T21:36:55.3634Z","caller":"traceutil/trace.go:171","msg":"trace[764027872] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:13747; }","duration":"286.915415ms","start":"2026-03-01T21:36:55.076462Z","end":"2026-03-01T21:36:55.363378Z","steps":["trace[764027872] 'agreement among raft nodes before linearized reading' (duration: 286.628677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.363253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.434626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:36:55.363458Z","caller":"traceutil/trace.go:171","msg":"trace[1282347048] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:13747; }","duration":"143.673003ms","start":"2026-03-01T21:36:55.219767Z","end":"2026-03-01T21:36:55.36344Z","steps":["trace[1282347048] 'agreement among raft nodes before linearized reading' (duration: 143.351064ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:36:55.363452Z","caller":"traceutil/trace.go:171","msg":"trace[408953832] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v4jht; range_end:; response_count:1; response_revision:13747; }","duration":"417.582422ms","start":"2026-03-01T21:36:54.945835Z","end":"2026-03-01T21:36:55.363417Z","steps":["trace[408953832] 'agreement among raft nodes before linearized reading' (duration: 417.187842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.363515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:54.945804Z","time spent":"417.702305ms","remote":"127.0.0.1:43382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-v4jht\" "} {"level":"warn","ts":"2026-03-01T21:36:55.364307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.444272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714938"} {"level":"info","ts":"2026-03-01T21:36:55.364362Z","caller":"traceutil/trace.go:171","msg":"trace[635326958] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:13747; }","duration":"321.547415ms","start":"2026-03-01T21:36:55.042796Z","end":"2026-03-01T21:36:55.364344Z","steps":["trace[635326958] 'agreement among raft nodes before linearized reading' (duration: 320.531072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.364402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:55.04276Z","time spent":"321.629687ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":82,"response size":714961,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-03-01T21:36:55.727849Z","caller":"traceutil/trace.go:171","msg":"trace[1976640859] transaction","detail":"{read_only:false; response_revision:13748; number_of_response:1; }","duration":"359.670697ms","start":"2026-03-01T21:36:55.368147Z","end":"2026-03-01T21:36:55.727817Z","steps":["trace[1976640859] 'process raft request' (duration: 349.355946ms)","trace[1976640859] 'compare' (duration: 10.158808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:36:55.728022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:55.368127Z","time spent":"359.79817ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:36:55.729383Z","caller":"traceutil/trace.go:171","msg":"trace[1518704756] linearizableReadLoop","detail":"{readStateIndex:14428; appliedIndex:14424; }","duration":"341.360888ms","start":"2026-03-01T21:36:55.387998Z","end":"2026-03-01T21:36:55.729359Z","steps":["trace[1518704756] 'read index received' (duration: 329.528201ms)","trace[1518704756] 'applied index is now lower than readState.Index' (duration: 11.831827ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:36:55.72951Z","caller":"traceutil/trace.go:171","msg":"trace[416211328] transaction","detail":"{read_only:false; response_revision:13751; number_of_response:1; }","duration":"359.476502ms","start":"2026-03-01T21:36:55.370021Z","end":"2026-03-01T21:36:55.729498Z","steps":["trace[416211328] 'process raft request' (duration: 359.301498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.729634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:55.370001Z","time spent":"359.545313ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:36:55.729677Z","caller":"traceutil/trace.go:171","msg":"trace[1750689205] transaction","detail":"{read_only:false; response_revision:13749; number_of_response:1; }","duration":"361.340526ms","start":"2026-03-01T21:36:55.368264Z","end":"2026-03-01T21:36:55.729604Z","steps":["trace[1750689205] 'process raft request' (duration: 360.865345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.729947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:55.36824Z","time spent":"361.561151ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:36:55.730019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.520421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:36:55.730122Z","caller":"traceutil/trace.go:171","msg":"trace[249439161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13751; }","duration":"194.685375ms","start":"2026-03-01T21:36:55.53541Z","end":"2026-03-01T21:36:55.730096Z","steps":["trace[249439161] 'agreement among raft nodes before linearized reading' (duration: 194.517561ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:36:55.730446Z","caller":"traceutil/trace.go:171","msg":"trace[2136036903] transaction","detail":"{read_only:false; response_revision:13750; number_of_response:1; }","duration":"361.273764ms","start":"2026-03-01T21:36:55.369067Z","end":"2026-03-01T21:36:55.730341Z","steps":["trace[2136036903] 'process raft request' (duration: 360.217339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.731804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:55.369055Z","time spent":"362.664016ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:36:55.733658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.65986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714938"} {"level":"info","ts":"2026-03-01T21:36:55.733729Z","caller":"traceutil/trace.go:171","msg":"trace[1493724641] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:13751; }","duration":"345.757181ms","start":"2026-03-01T21:36:55.387958Z","end":"2026-03-01T21:36:55.733715Z","steps":["trace[1493724641] 'agreement among raft nodes before linearized reading' (duration: 341.696406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:36:55.733775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:36:55.38794Z","time spent":"345.816313ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":82,"response size":714961,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-03-01T21:37:22.985729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11704} {"level":"info","ts":"2026-03-01T21:37:23.0656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11704,"took":"76.832067ms","hash":1489475954,"current-db-size-bytes":47345664,"current-db-size":"47 MB","current-db-size-in-use-bytes":26460160,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-01T21:37:23.065669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1489475954,"revision":11704,"compact-revision":9010}