{"level":"warn","ts":"2026-04-29T01:56:46.523861Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-29T01:56:46.523947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-29T01:56:46.524013Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-29T01:56:46.524023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-29T01:56:46.524051Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-29T01:56:46.52471Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-29T01:56:46.524787Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-29T01:56:46.761556Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"236.570275ms"} {"level":"info","ts":"2026-04-29T01:56:46.809936Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-29T01:56:46.810072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-29T01:56:46.810113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-29T01:56:46.810137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-29T01:56:46.810161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-29T01:56:46.810218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-29T01:56:46.839319Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-29T01:56:46.841925Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-29T01:56:46.843839Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-29T01:56:46.848717Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-29T01:56:46.848833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-29T01:56:46.848891Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-29T01:56:46.848901Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-29T01:56:46.84898Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-29T01:56:46.849924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-29T01:56:46.850023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-29T01:56:46.853785Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-29T01:56:46.854019Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-29T01:56:46.854055Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-29T01:56:46.854124Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-29T01:56:46.854142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-29T01:56:47.811402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-29T01:56:47.811461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-29T01:56:47.81151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-29T01:56:47.811531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-29T01:56:47.811543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-29T01:56:47.811554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-29T01:56:47.811565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-29T01:56:47.812548Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-29T01:56:47.812598Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-29T01:56:47.812714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-29T01:56:47.81274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T01:56:47.813816Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T01:56:47.813933Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T01:56:47.813971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-29T01:56:47.814014Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-29T01:56:47.814027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-29T01:56:47.815206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-29T01:56:47.817172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-29T01:56:52.683488Z","caller":"traceutil/trace.go:171","msg":"trace[776143990] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"134.039589ms","start":"2026-04-29T01:56:52.549424Z","end":"2026-04-29T01:56:52.683463Z","steps":["trace[776143990] 'read index received' (duration: 133.938547ms)","trace[776143990] 'applied index is now lower than readState.Index' (duration: 99.562µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T01:56:52.683559Z","caller":"traceutil/trace.go:171","msg":"trace[218743571] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"200.238868ms","start":"2026-04-29T01:56:52.483275Z","end":"2026-04-29T01:56:52.683513Z","steps":["trace[218743571] 'process raft request' (duration: 200.050343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:52.683703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.258725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T01:56:52.683844Z","caller":"traceutil/trace.go:171","msg":"trace[1230917496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:272; }","duration":"134.46839ms","start":"2026-04-29T01:56:52.549354Z","end":"2026-04-29T01:56:52.683822Z","steps":["trace[1230917496] 'agreement among raft nodes before linearized reading' (duration: 134.230194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.209676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.775113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-29T01:56:53.209786Z","caller":"traceutil/trace.go:171","msg":"trace[1992150907] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"519.711963ms","start":"2026-04-29T01:56:52.690058Z","end":"2026-04-29T01:56:53.20977Z","steps":["trace[1992150907] 'read index received' (duration: 297.118358ms)","trace[1992150907] 'applied index is now lower than readState.Index' (duration: 222.591925ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T01:56:53.209867Z","caller":"traceutil/trace.go:171","msg":"trace[806611690] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"521.587001ms","start":"2026-04-29T01:56:52.688211Z","end":"2026-04-29T01:56:53.209798Z","steps":["trace[806611690] 'process raft request' (duration: 298.802601ms)","trace[806611690] 'compare' (duration: 221.492596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T01:56:53.210073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.688184Z","time spent":"521.814626ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-29T01:56:53.209916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.849116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T01:56:53.210425Z","caller":"traceutil/trace.go:171","msg":"trace[670413917] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:273; }","duration":"520.378149ms","start":"2026-04-29T01:56:52.690033Z","end":"2026-04-29T01:56:53.210411Z","steps":["trace[670413917] 'agreement among raft nodes before linearized reading' (duration: 519.830765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.210467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.690019Z","time spent":"520.433861ms","remote":"127.0.0.1:40346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":26,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-04-29T01:56:53.255928Z","caller":"traceutil/trace.go:171","msg":"trace[1938125965] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"523.738036ms","start":"2026-04-29T01:56:52.732164Z","end":"2026-04-29T01:56:53.255902Z","steps":["trace[1938125965] 'process raft request' (duration: 523.438428ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.256022Z","caller":"traceutil/trace.go:171","msg":"trace[1912626962] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"422.890253ms","start":"2026-04-29T01:56:52.833118Z","end":"2026-04-29T01:56:53.256008Z","steps":["trace[1912626962] 'process raft request' (duration: 422.692228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.256087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.833088Z","time spent":"422.970996ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-29T01:56:53.256083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.732153Z","time spent":"523.858468ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-29T01:56:53.256213Z","caller":"traceutil/trace.go:171","msg":"trace[496424474] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"405.593286ms","start":"2026-04-29T01:56:52.850581Z","end":"2026-04-29T01:56:53.256174Z","steps":["trace[496424474] 'process raft request' (duration: 405.291198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.256311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.850548Z","time spent":"405.72213ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-29T01:56:53.256489Z","caller":"traceutil/trace.go:171","msg":"trace[1061907303] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"405.517974ms","start":"2026-04-29T01:56:52.850956Z","end":"2026-04-29T01:56:53.256474Z","steps":["trace[1061907303] 'process raft request' (duration: 405.031672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.256544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.850947Z","time spent":"405.574335ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-29T01:56:53.257003Z","caller":"traceutil/trace.go:171","msg":"trace[2103157196] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"405.844804ms","start":"2026-04-29T01:56:52.851115Z","end":"2026-04-29T01:56:53.25696Z","steps":["trace[2103157196] 'process raft request' (duration: 405.660708ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.257102Z","caller":"traceutil/trace.go:171","msg":"trace[1408901853] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"405.885594ms","start":"2026-04-29T01:56:52.85114Z","end":"2026-04-29T01:56:53.257026Z","steps":["trace[1408901853] 'process raft request' (duration: 405.689709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.257227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.85108Z","time spent":"406.063329ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-29T01:56:53.257257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.401619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-29T01:56:53.25728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.851107Z","time spent":"406.08628ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-29T01:56:53.25734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.020475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T01:56:53.257366Z","caller":"traceutil/trace.go:171","msg":"trace[356866492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:279; }","duration":"488.079266ms","start":"2026-04-29T01:56:52.769279Z","end":"2026-04-29T01:56:53.257359Z","steps":["trace[356866492] 'agreement among raft nodes before linearized reading' (duration: 488.040085ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.257357Z","caller":"traceutil/trace.go:171","msg":"trace[1751425018] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"405.604117ms","start":"2026-04-29T01:56:52.851721Z","end":"2026-04-29T01:56:53.257325Z","steps":["trace[1751425018] 'process raft request' (duration: 405.194407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.257397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.769246Z","time spent":"488.145958ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-29T01:56:53.257298Z","caller":"traceutil/trace.go:171","msg":"trace[192980375] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:279; }","duration":"525.45427ms","start":"2026-04-29T01:56:52.731831Z","end":"2026-04-29T01:56:53.257286Z","steps":["trace[192980375] 'agreement among raft nodes before linearized reading' (duration: 525.350877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.257536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.069455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-29T01:56:53.257582Z","caller":"traceutil/trace.go:171","msg":"trace[412896927] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:279; }","duration":"567.150637ms","start":"2026-04-29T01:56:52.690416Z","end":"2026-04-29T01:56:53.257567Z","steps":["trace[412896927] 'agreement among raft nodes before linearized reading' (duration: 567.034844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.257615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.860771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-29T01:56:53.257625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.690408Z","time spent":"567.209508ms","remote":"127.0.0.1:40350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-29T01:56:53.257618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.731827Z","time spent":"525.739278ms","remote":"127.0.0.1:40604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":685,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-29T01:56:53.257638Z","caller":"traceutil/trace.go:171","msg":"trace[619285607] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:279; }","duration":"525.921573ms","start":"2026-04-29T01:56:52.73171Z","end":"2026-04-29T01:56:53.257632Z","steps":["trace[619285607] 'agreement among raft nodes before linearized reading' (duration: 525.882462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.257671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.731689Z","time spent":"525.977924ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-29T01:56:53.257506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:56:52.851704Z","time spent":"405.726431ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-29T01:56:53.45033Z","caller":"traceutil/trace.go:171","msg":"trace[1280581256] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"187.996322ms","start":"2026-04-29T01:56:53.262311Z","end":"2026-04-29T01:56:53.450307Z","steps":["trace[1280581256] 'process raft request' (duration: 187.93331ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.450385Z","caller":"traceutil/trace.go:171","msg":"trace[1290297356] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"188.808862ms","start":"2026-04-29T01:56:53.261555Z","end":"2026-04-29T01:56:53.450364Z","steps":["trace[1290297356] 'process raft request' (duration: 188.647428ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.450422Z","caller":"traceutil/trace.go:171","msg":"trace[1411511884] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"193.424161ms","start":"2026-04-29T01:56:53.256981Z","end":"2026-04-29T01:56:53.450405Z","steps":["trace[1411511884] 'read index received' (duration: 103.882441ms)","trace[1411511884] 'applied index is now lower than readState.Index' (duration: 89.53953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T01:56:53.450588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.77336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T01:56:53.450338Z","caller":"traceutil/trace.go:171","msg":"trace[203266131] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"189.178182ms","start":"2026-04-29T01:56:53.261101Z","end":"2026-04-29T01:56:53.450279Z","steps":["trace[203266131] 'process raft request' (duration: 189.054229ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.450692Z","caller":"traceutil/trace.go:171","msg":"trace[793686093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:283; }","duration":"217.908783ms","start":"2026-04-29T01:56:53.232759Z","end":"2026-04-29T01:56:53.450668Z","steps":["trace[793686093] 'agreement among raft nodes before linearized reading' (duration: 217.684837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.450621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.282238ms","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-04-29T01:56:53.450831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.248204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-29T01:56:53.45089Z","caller":"traceutil/trace.go:171","msg":"trace[1072740962] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:283; }","duration":"189.345916ms","start":"2026-04-29T01:56:53.261525Z","end":"2026-04-29T01:56:53.450871Z","steps":["trace[1072740962] 'agreement among raft nodes before linearized reading' (duration: 189.232073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.450887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.537991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-04-29T01:56:53.450957Z","caller":"traceutil/trace.go:171","msg":"trace[852632054] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:283; }","duration":"189.624294ms","start":"2026-04-29T01:56:53.261313Z","end":"2026-04-29T01:56:53.450938Z","steps":["trace[852632054] 'agreement among raft nodes before linearized reading' (duration: 189.4773ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:56:53.450278Z","caller":"traceutil/trace.go:171","msg":"trace[1949330144] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"235.260231ms","start":"2026-04-29T01:56:53.214966Z","end":"2026-04-29T01:56:53.450227Z","steps":["trace[1949330144] 'process raft request' (duration: 145.767282ms)","trace[1949330144] 'compare' (duration: 89.19118ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T01:56:53.451021Z","caller":"traceutil/trace.go:171","msg":"trace[582841509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:283; }","duration":"236.556795ms","start":"2026-04-29T01:56:53.214263Z","end":"2026-04-29T01:56:53.45082Z","steps":["trace[582841509] 'agreement among raft nodes before linearized reading' (duration: 236.182986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:56:53.451526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.232342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-04-29T01:56:53.451582Z","caller":"traceutil/trace.go:171","msg":"trace[1292531914] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:283; }","duration":"194.455698ms","start":"2026-04-29T01:56:53.257109Z","end":"2026-04-29T01:56:53.451564Z","steps":["trace[1292531914] 'agreement among raft nodes before linearized reading' (duration: 193.564275ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:23.582512Z","caller":"traceutil/trace.go:171","msg":"trace[24833139] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"105.704246ms","start":"2026-04-29T01:57:23.476783Z","end":"2026-04-29T01:57:23.582487Z","steps":["trace[24833139] 'process raft request' (duration: 105.627574ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:23.58283Z","caller":"traceutil/trace.go:171","msg":"trace[1684034613] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"108.892577ms","start":"2026-04-29T01:57:23.473925Z","end":"2026-04-29T01:57:23.582817Z","steps":["trace[1684034613] 'process raft request' (duration: 101.422207ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:23.582927Z","caller":"traceutil/trace.go:171","msg":"trace[1363108089] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"107.027999ms","start":"2026-04-29T01:57:23.475888Z","end":"2026-04-29T01:57:23.582916Z","steps":["trace[1363108089] 'process raft request' (duration: 106.433404ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:23.582907Z","caller":"traceutil/trace.go:171","msg":"trace[1663230403] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"108.901077ms","start":"2026-04-29T01:57:23.473973Z","end":"2026-04-29T01:57:23.582875Z","steps":["trace[1663230403] 'process raft request' (duration: 108.176079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:57:29.775091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.050813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-29T01:57:29.775417Z","caller":"traceutil/trace.go:171","msg":"trace[646095058] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"111.561873ms","start":"2026-04-29T01:57:29.663827Z","end":"2026-04-29T01:57:29.775389Z","steps":["trace[646095058] 'process raft request' (duration: 10.676839ms)","trace[646095058] 'compare' (duration: 99.842628ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T01:57:29.775585Z","caller":"traceutil/trace.go:171","msg":"trace[1820278444] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"110.667091ms","start":"2026-04-29T01:57:29.6649Z","end":"2026-04-29T01:57:29.775567Z","steps":["trace[1820278444] 'process raft request' (duration: 110.526787ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:29.775695Z","caller":"traceutil/trace.go:171","msg":"trace[941360430] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"111.010051ms","start":"2026-04-29T01:57:29.664645Z","end":"2026-04-29T01:57:29.775655Z","steps":["trace[941360430] 'process raft request' (duration: 110.689272ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:29.996786Z","caller":"traceutil/trace.go:171","msg":"trace[1998620984] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"113.434642ms","start":"2026-04-29T01:57:29.883318Z","end":"2026-04-29T01:57:29.996753Z","steps":["trace[1998620984] 'process raft request' (duration: 113.231857ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:57:30.811297Z","caller":"traceutil/trace.go:171","msg":"trace[1054456739] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"497.508905ms","start":"2026-04-29T01:57:30.313759Z","end":"2026-04-29T01:57:30.811268Z","steps":["trace[1054456739] 'read index received' (duration: 496.50677ms)","trace[1054456739] 'applied index is now lower than readState.Index' (duration: 1.001135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T01:57:30.811465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.69713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-29T01:57:30.8115Z","caller":"traceutil/trace.go:171","msg":"trace[1951479052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"497.808572ms","start":"2026-04-29T01:57:30.313678Z","end":"2026-04-29T01:57:30.811487Z","steps":["trace[1951479052] 'agreement among raft nodes before linearized reading' (duration: 497.69744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:57:30.811628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:57:30.313643Z","time spent":"497.885504ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-29T01:57:30.811696Z","caller":"traceutil/trace.go:171","msg":"trace[1919235727] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"771.717478ms","start":"2026-04-29T01:57:30.039715Z","end":"2026-04-29T01:57:30.811433Z","steps":["trace[1919235727] 'process raft request' (duration: 770.824136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:57:30.812046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T01:57:30.039669Z","time spent":"772.151059ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T01:57:54.285251Z","caller":"traceutil/trace.go:171","msg":"trace[328738453] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"270.13347ms","start":"2026-04-29T01:57:54.015081Z","end":"2026-04-29T01:57:54.285215Z","steps":["trace[328738453] 'process raft request' (duration: 269.852643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:59:10.005418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.013989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07d5256b-74d5-49e7-bb06-97b7f2297697\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-29T01:59:10.005509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.862798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-29T01:59:10.005633Z","caller":"traceutil/trace.go:171","msg":"trace[1667162252] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1882; }","duration":"219.056792ms","start":"2026-04-29T01:59:09.786543Z","end":"2026-04-29T01:59:10.005599Z","steps":["trace[1667162252] 'range keys from in-memory index tree' (duration: 218.621681ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:59:10.005553Z","caller":"traceutil/trace.go:171","msg":"trace[426992302] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07d5256b-74d5-49e7-bb06-97b7f2297697; range_end:; response_count:1; response_revision:1882; }","duration":"223.191384ms","start":"2026-04-29T01:59:09.782313Z","end":"2026-04-29T01:59:10.005505Z","steps":["trace[426992302] 'range keys from in-memory index tree' (duration: 222.759033ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:59:10.449211Z","caller":"traceutil/trace.go:171","msg":"trace[676041295] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"143.6811ms","start":"2026-04-29T01:59:10.305497Z","end":"2026-04-29T01:59:10.449179Z","steps":["trace[676041295] 'process raft request' (duration: 143.418094ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:59:10.449234Z","caller":"traceutil/trace.go:171","msg":"trace[574936139] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"143.102326ms","start":"2026-04-29T01:59:10.306103Z","end":"2026-04-29T01:59:10.449206Z","steps":["trace[574936139] 'process raft request' (duration: 142.998263ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T01:59:59.519713Z","caller":"traceutil/trace.go:171","msg":"trace[1359751479] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"124.268245ms","start":"2026-04-29T01:59:59.395419Z","end":"2026-04-29T01:59:59.519687Z","steps":["trace[1359751479] 'read index received' (duration: 123.994388ms)","trace[1359751479] 'applied index is now lower than readState.Index' (duration: 272.927µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T01:59:59.519793Z","caller":"traceutil/trace.go:171","msg":"trace[1138027181] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"197.875122ms","start":"2026-04-29T01:59:59.321884Z","end":"2026-04-29T01:59:59.519759Z","steps":["trace[1138027181] 'process raft request' (duration: 197.587545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:59:59.520036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.577532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-29T01:59:59.520154Z","caller":"traceutil/trace.go:171","msg":"trace[1952244613] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2202; }","duration":"124.753256ms","start":"2026-04-29T01:59:59.395375Z","end":"2026-04-29T01:59:59.520128Z","steps":["trace[1952244613] 'agreement among raft nodes before linearized reading' (duration: 124.429968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T01:59:59.772161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.018209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-29T01:59:59.772294Z","caller":"traceutil/trace.go:171","msg":"trace[794113059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2202; }","duration":"156.214785ms","start":"2026-04-29T01:59:59.616044Z","end":"2026-04-29T01:59:59.772259Z","steps":["trace[794113059] 'range keys from in-memory index tree' (duration: 155.730103ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:00:49.849171Z","caller":"traceutil/trace.go:171","msg":"trace[62606982] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"150.000295ms","start":"2026-04-29T02:00:49.699124Z","end":"2026-04-29T02:00:49.849125Z","steps":["trace[62606982] 'process raft request' (duration: 149.724239ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:04:04.903958Z","caller":"traceutil/trace.go:171","msg":"trace[1463899986] transaction","detail":"{read_only:false; response_revision:3902; number_of_response:1; }","duration":"105.07257ms","start":"2026-04-29T02:04:04.798859Z","end":"2026-04-29T02:04:04.903931Z","steps":["trace[1463899986] 'process raft request' (duration: 104.307972ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:05:29.925331Z","caller":"traceutil/trace.go:171","msg":"trace[2145590841] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"130.147591ms","start":"2026-04-29T02:05:29.79513Z","end":"2026-04-29T02:05:29.925277Z","steps":["trace[2145590841] 'process raft request' (duration: 113.167843ms)","trace[2145590841] 'compare' (duration: 16.67273ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:05:52.366375Z","caller":"traceutil/trace.go:171","msg":"trace[1752366393] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"190.230693ms","start":"2026-04-29T02:05:52.176114Z","end":"2026-04-29T02:05:52.366345Z","steps":["trace[1752366393] 'process raft request' (duration: 190.007868ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:05:52.416954Z","caller":"traceutil/trace.go:171","msg":"trace[1494373889] linearizableReadLoop","detail":"{readStateIndex:5706; appliedIndex:5704; }","duration":"206.672628ms","start":"2026-04-29T02:05:52.210239Z","end":"2026-04-29T02:05:52.416911Z","steps":["trace[1494373889] 'read index received' (duration: 155.92254ms)","trace[1494373889] 'applied index is now lower than readState.Index' (duration: 50.744038ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:05:52.417096Z","caller":"traceutil/trace.go:171","msg":"trace[612162639] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"236.234696ms","start":"2026-04-29T02:05:52.180822Z","end":"2026-04-29T02:05:52.417057Z","steps":["trace[612162639] 'process raft request' (duration: 235.887978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:05:52.417208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.941724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T02:05:52.417301Z","caller":"traceutil/trace.go:171","msg":"trace[1079456812] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5496; }","duration":"207.110577ms","start":"2026-04-29T02:05:52.21017Z","end":"2026-04-29T02:05:52.41728Z","steps":["trace[1079456812] 'agreement among raft nodes before linearized reading' (duration: 206.913353ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:06:47.837911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2987} {"level":"info","ts":"2026-04-29T02:06:47.906132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2987,"took":"66.363453ms","hash":2752954893,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":33280000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-29T02:06:47.90621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2752954893,"revision":2987,"compact-revision":-1} {"level":"info","ts":"2026-04-29T02:11:47.843729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6017} {"level":"info","ts":"2026-04-29T02:11:47.933114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6017,"took":"87.604479ms","hash":3884816120,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":21880832,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-29T02:11:47.933198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3884816120,"revision":6017,"compact-revision":2987} {"level":"info","ts":"2026-04-29T02:11:52.971865Z","caller":"traceutil/trace.go:171","msg":"trace[1746711857] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"287.710658ms","start":"2026-04-29T02:11:52.684116Z","end":"2026-04-29T02:11:52.971827Z","steps":["trace[1746711857] 'process raft request' (duration: 287.393561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:11:52.972117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.58689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-29T02:11:52.972442Z","caller":"traceutil/trace.go:171","msg":"trace[177494524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8304; }","duration":"210.95969ms","start":"2026-04-29T02:11:52.761474Z","end":"2026-04-29T02:11:52.972433Z","steps":["trace[177494524] 'agreement among raft nodes before linearized reading' (duration: 210.493748ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:11:52.972471Z","caller":"traceutil/trace.go:171","msg":"trace[526745649] linearizableReadLoop","detail":"{readStateIndex:8603; appliedIndex:8602; }","duration":"210.188101ms","start":"2026-04-29T02:11:52.761534Z","end":"2026-04-29T02:11:52.971722Z","steps":["trace[526745649] 'read index received' (duration: 209.830802ms)","trace[526745649] 'applied index is now lower than readState.Index' (duration: 355.619µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:12:04.731034Z","caller":"traceutil/trace.go:171","msg":"trace[1794132793] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"118.451794ms","start":"2026-04-29T02:12:04.612555Z","end":"2026-04-29T02:12:04.731006Z","steps":["trace[1794132793] 'process raft request' (duration: 118.30685ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:12:04.811004Z","caller":"traceutil/trace.go:171","msg":"trace[1312664675] linearizableReadLoop","detail":"{readStateIndex:8665; appliedIndex:8663; }","duration":"111.950779ms","start":"2026-04-29T02:12:04.699015Z","end":"2026-04-29T02:12:04.810966Z","steps":["trace[1312664675] 'read index received' (duration: 31.922721ms)","trace[1312664675] 'applied index is now lower than readState.Index' (duration: 80.026408ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:12:04.811091Z","caller":"traceutil/trace.go:171","msg":"trace[1859958856] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"198.00498ms","start":"2026-04-29T02:12:04.61305Z","end":"2026-04-29T02:12:04.811055Z","steps":["trace[1859958856] 'process raft request' (duration: 167.081053ms)","trace[1859958856] 'compare' (duration: 30.690591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:12:04.811255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.212145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-29T02:12:04.811309Z","caller":"traceutil/trace.go:171","msg":"trace[1938580712] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8364; }","duration":"112.315618ms","start":"2026-04-29T02:12:04.698977Z","end":"2026-04-29T02:12:04.811292Z","steps":["trace[1938580712] 'agreement among raft nodes before linearized reading' (duration: 112.124253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:12:05.74189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.614386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-29T02:12:05.741981Z","caller":"traceutil/trace.go:171","msg":"trace[542279974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8369; }","duration":"193.79744ms","start":"2026-04-29T02:12:05.548162Z","end":"2026-04-29T02:12:05.74196Z","steps":["trace[542279974] 'range keys from in-memory index tree' (duration: 193.141165ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:12:06.481805Z","caller":"traceutil/trace.go:171","msg":"trace[606102941] transaction","detail":"{read_only:false; response_revision:8373; number_of_response:1; }","duration":"104.548282ms","start":"2026-04-29T02:12:06.377222Z","end":"2026-04-29T02:12:06.48177Z","steps":["trace[606102941] 'process raft request' (duration: 104.354488ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:12:06.869421Z","caller":"traceutil/trace.go:171","msg":"trace[329564020] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"122.548971ms","start":"2026-04-29T02:12:06.746822Z","end":"2026-04-29T02:12:06.869371Z","steps":["trace[329564020] 'process raft request' (duration: 102.927883ms)","trace[329564020] 'compare' (duration: 19.199218ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:12:32.883931Z","caller":"traceutil/trace.go:171","msg":"trace[752533355] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"252.407487ms","start":"2026-04-29T02:12:32.6315Z","end":"2026-04-29T02:12:32.883908Z","steps":["trace[752533355] 'process raft request' (duration: 252.281454ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:12:36.067811Z","caller":"traceutil/trace.go:171","msg":"trace[823773971] linearizableReadLoop","detail":"{readStateIndex:8894; appliedIndex:8893; }","duration":"151.908962ms","start":"2026-04-29T02:12:35.915878Z","end":"2026-04-29T02:12:36.067787Z","steps":["trace[823773971] 'read index received' (duration: 151.653396ms)","trace[823773971] 'applied index is now lower than readState.Index' (duration: 254.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:12:36.06786Z","caller":"traceutil/trace.go:171","msg":"trace[124095666] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"158.765344ms","start":"2026-04-29T02:12:35.909062Z","end":"2026-04-29T02:12:36.067827Z","steps":["trace[124095666] 'process raft request' (duration: 158.56788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:12:36.068109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.192728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-29T02:12:36.068208Z","caller":"traceutil/trace.go:171","msg":"trace[608388018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8584; }","duration":"152.307491ms","start":"2026-04-29T02:12:35.915872Z","end":"2026-04-29T02:12:36.06818Z","steps":["trace[608388018] 'agreement among raft nodes before linearized reading' (duration: 152.017654ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:12:45.979857Z","caller":"traceutil/trace.go:171","msg":"trace[1328668400] transaction","detail":"{read_only:false; response_revision:8675; number_of_response:1; }","duration":"154.171286ms","start":"2026-04-29T02:12:45.82566Z","end":"2026-04-29T02:12:45.979831Z","steps":["trace[1328668400] 'process raft request' (duration: 94.188816ms)","trace[1328668400] 'compare' (duration: 59.775245ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:12:45.980032Z","caller":"traceutil/trace.go:171","msg":"trace[1717466068] linearizableReadLoop","detail":"{readStateIndex:8990; appliedIndex:8988; }","duration":"151.981344ms","start":"2026-04-29T02:12:45.828024Z","end":"2026-04-29T02:12:45.980005Z","steps":["trace[1717466068] 'read index received' (duration: 3.652698ms)","trace[1717466068] 'applied index is now lower than readState.Index' (duration: 148.327716ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:12:45.980249Z","caller":"traceutil/trace.go:171","msg":"trace[1576946257] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"148.954582ms","start":"2026-04-29T02:12:45.831273Z","end":"2026-04-29T02:12:45.980228Z","steps":["trace[1576946257] 'process raft request' (duration: 148.497021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:12:45.980343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.298201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-29T02:12:45.980406Z","caller":"traceutil/trace.go:171","msg":"trace[310177660] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8676; }","duration":"152.385043ms","start":"2026-04-29T02:12:45.828008Z","end":"2026-04-29T02:12:45.980393Z","steps":["trace[310177660] 'agreement among raft nodes before linearized reading' (duration: 152.204879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:12:47.34185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.685181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-29T02:12:47.341952Z","caller":"traceutil/trace.go:171","msg":"trace[299515681] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8690; }","duration":"139.836634ms","start":"2026-04-29T02:12:47.202092Z","end":"2026-04-29T02:12:47.341929Z","steps":["trace[299515681] 'range keys from in-memory index tree' (duration: 139.447285ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:12:47.352168Z","caller":"traceutil/trace.go:171","msg":"trace[1587303332] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"129.107658ms","start":"2026-04-29T02:12:47.223016Z","end":"2026-04-29T02:12:47.352124Z","steps":["trace[1587303332] 'process raft request' (duration: 128.921764ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:13:10.369069Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002446.wal"} {"level":"info","ts":"2026-04-29T02:14:33.927641Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T02:14:33.934316Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-29T02:14:33.934462Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-29T02:16:47.855926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8144} {"level":"info","ts":"2026-04-29T02:16:47.936745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8144,"took":"78.398726ms","hash":3785979451,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":29806592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-29T02:16:47.936829Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3785979451,"revision":8144,"compact-revision":6017} {"level":"info","ts":"2026-04-29T02:17:38.508286Z","caller":"traceutil/trace.go:171","msg":"trace[564821037] transaction","detail":"{read_only:false; response_revision:11236; number_of_response:1; }","duration":"110.958571ms","start":"2026-04-29T02:17:38.397307Z","end":"2026-04-29T02:17:38.508265Z","steps":["trace[564821037] 'process raft request' (duration: 106.136666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:17:38.835165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.823956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T02:17:38.835232Z","caller":"traceutil/trace.go:171","msg":"trace[1733916829] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11236; }","duration":"115.950189ms","start":"2026-04-29T02:17:38.719267Z","end":"2026-04-29T02:17:38.835217Z","steps":["trace[1733916829] 'range keys from in-memory index tree' (duration: 115.749895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:17:38.835287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.017001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T02:17:38.835487Z","caller":"traceutil/trace.go:171","msg":"trace[2006518098] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11236; }","duration":"116.279187ms","start":"2026-04-29T02:17:38.719182Z","end":"2026-04-29T02:17:38.835461Z","steps":["trace[2006518098] 'range keys from in-memory index tree' (duration: 115.908939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:17:38.835433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.151774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-29T02:17:38.835461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.132321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-29T02:17:38.835546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.092059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-04-29T02:17:38.835578Z","caller":"traceutil/trace.go:171","msg":"trace[1466525848] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11236; }","duration":"115.155881ms","start":"2026-04-29T02:17:38.720414Z","end":"2026-04-29T02:17:38.83557Z","steps":["trace[1466525848] 'range keys from in-memory index tree' (duration: 114.963906ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:17:38.835566Z","caller":"traceutil/trace.go:171","msg":"trace[888201885] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11236; }","duration":"116.306408ms","start":"2026-04-29T02:17:38.719237Z","end":"2026-04-29T02:17:38.835544Z","steps":["trace[888201885] 'range keys from in-memory index tree' (duration: 116.073852ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:17:38.835608Z","caller":"traceutil/trace.go:171","msg":"trace[945909646] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:11236; }","duration":"202.336726ms","start":"2026-04-29T02:17:38.633222Z","end":"2026-04-29T02:17:38.835558Z","steps":["trace[945909646] 'count revisions from in-memory index tree' (duration: 202.004039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:17:38.835791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.270608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-29T02:17:38.835925Z","caller":"traceutil/trace.go:171","msg":"trace[14503155] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11236; }","duration":"116.479173ms","start":"2026-04-29T02:17:38.719366Z","end":"2026-04-29T02:17:38.835845Z","steps":["trace[14503155] 'range keys from in-memory index tree' (duration: 116.197465ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:21:47.862089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10762} {"level":"info","ts":"2026-04-29T02:21:47.945663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10762,"took":"80.911655ms","hash":3910503315,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":27766784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-29T02:21:47.945751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3910503315,"revision":10762,"compact-revision":8144} {"level":"info","ts":"2026-04-29T02:24:22.797165Z","caller":"traceutil/trace.go:171","msg":"trace[779843449] linearizableReadLoop","detail":"{readStateIndex:14622; appliedIndex:14621; }","duration":"149.214375ms","start":"2026-04-29T02:24:22.647924Z","end":"2026-04-29T02:24:22.797139Z","steps":["trace[779843449] 'read index received' (duration: 149.043121ms)","trace[779843449] 'applied index is now lower than readState.Index' (duration: 170.014µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:24:22.797256Z","caller":"traceutil/trace.go:171","msg":"trace[683406908] transaction","detail":"{read_only:false; response_revision:14127; number_of_response:1; }","duration":"231.1662ms","start":"2026-04-29T02:24:22.566048Z","end":"2026-04-29T02:24:22.797214Z","steps":["trace[683406908] 'process raft request' (duration: 230.936585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:24:22.797305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.353418ms","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":"info","ts":"2026-04-29T02:24:22.797342Z","caller":"traceutil/trace.go:171","msg":"trace[2145011851] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:14127; }","duration":"149.416319ms","start":"2026-04-29T02:24:22.647914Z","end":"2026-04-29T02:24:22.79733Z","steps":["trace[2145011851] 'agreement among raft nodes before linearized reading' (duration: 149.326707ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:24:22.823537Z","caller":"traceutil/trace.go:171","msg":"trace[917561052] transaction","detail":"{read_only:false; response_revision:14128; number_of_response:1; }","duration":"127.5374ms","start":"2026-04-29T02:24:22.695981Z","end":"2026-04-29T02:24:22.823518Z","steps":["trace[917561052] 'process raft request' (duration: 118.887344ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:25:49.241348Z","caller":"traceutil/trace.go:171","msg":"trace[984201744] transaction","detail":"{read_only:false; response_revision:14937; number_of_response:1; }","duration":"101.323567ms","start":"2026-04-29T02:25:49.139998Z","end":"2026-04-29T02:25:49.241321Z","steps":["trace[984201744] 'process raft request' (duration: 100.787374ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:25:49.381906Z","caller":"traceutil/trace.go:171","msg":"trace[1620254358] transaction","detail":"{read_only:false; response_revision:14938; number_of_response:1; }","duration":"133.417379ms","start":"2026-04-29T02:25:49.24846Z","end":"2026-04-29T02:25:49.381878Z","steps":["trace[1620254358] 'process raft request' (duration: 122.455318ms)","trace[1620254358] 'compare' (duration: 10.505369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:25:49.674371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.200406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mrb54\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-04-29T02:25:49.674498Z","caller":"traceutil/trace.go:171","msg":"trace[1704070284] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mrb54; range_end:; response_count:1; response_revision:14943; }","duration":"155.404142ms","start":"2026-04-29T02:25:49.519069Z","end":"2026-04-29T02:25:49.674473Z","steps":["trace[1704070284] 'range keys from in-memory index tree' (duration: 154.94281ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:26:47.866955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12838} {"level":"info","ts":"2026-04-29T02:26:47.94099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12838,"took":"71.59995ms","hash":1212721711,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":30744576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-29T02:26:47.94105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1212721711,"revision":12838,"compact-revision":10762} {"level":"warn","ts":"2026-04-29T02:31:05.489831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.128971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:31:05.490034Z","caller":"traceutil/trace.go:171","msg":"trace[538618836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17370; }","duration":"119.384747ms","start":"2026-04-29T02:31:05.370616Z","end":"2026-04-29T02:31:05.490001Z","steps":["trace[538618836] 'range keys from in-memory index tree' (duration: 119.026718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:31:05.490292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.957737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mrb54\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-04-29T02:31:05.490497Z","caller":"traceutil/trace.go:171","msg":"trace[1453504878] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mrb54; range_end:; response_count:1; response_revision:17370; }","duration":"122.201243ms","start":"2026-04-29T02:31:05.368225Z","end":"2026-04-29T02:31:05.490426Z","steps":["trace[1453504878] 'range keys from in-memory index tree' (duration: 121.65682ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:31:12.494039Z","caller":"traceutil/trace.go:171","msg":"trace[1056659653] linearizableReadLoop","detail":"{readStateIndex:18002; appliedIndex:18001; }","duration":"163.557075ms","start":"2026-04-29T02:31:12.330453Z","end":"2026-04-29T02:31:12.49401Z","steps":["trace[1056659653] 'read index received' (duration: 163.35906ms)","trace[1056659653] 'applied index is now lower than readState.Index' (duration: 196.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:31:12.494149Z","caller":"traceutil/trace.go:171","msg":"trace[1071504455] transaction","detail":"{read_only:false; response_revision:17406; number_of_response:1; }","duration":"223.177971ms","start":"2026-04-29T02:31:12.270936Z","end":"2026-04-29T02:31:12.494114Z","steps":["trace[1071504455] 'process raft request' (duration: 222.881894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:31:12.494359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.884682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qnjfx\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-04-29T02:31:12.49441Z","caller":"traceutil/trace.go:171","msg":"trace[1124370272] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qnjfx; range_end:; response_count:1; response_revision:17406; }","duration":"164.003625ms","start":"2026-04-29T02:31:12.330393Z","end":"2026-04-29T02:31:12.494396Z","steps":["trace[1124370272] 'agreement among raft nodes before linearized reading' (duration: 163.766969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:31:12.53014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.830666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:31:12.530231Z","caller":"traceutil/trace.go:171","msg":"trace[814892184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17407; }","duration":"159.98519ms","start":"2026-04-29T02:31:12.370224Z","end":"2026-04-29T02:31:12.530209Z","steps":["trace[814892184] 'agreement among raft nodes before linearized reading' (duration: 159.820496ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:31:12.992632Z","caller":"traceutil/trace.go:171","msg":"trace[1269174534] transaction","detail":"{read_only:false; response_revision:17409; number_of_response:1; }","duration":"439.899269ms","start":"2026-04-29T02:31:12.552687Z","end":"2026-04-29T02:31:12.992586Z","steps":["trace[1269174534] 'process raft request' (duration: 439.689494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:31:12.992882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:31:12.552672Z","time spent":"440.067743ms","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2716,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:31:13.004351Z","caller":"traceutil/trace.go:171","msg":"trace[379675696] linearizableReadLoop","detail":"{readStateIndex:18006; appliedIndex:18005; }","duration":"381.166654ms","start":"2026-04-29T02:31:12.623154Z","end":"2026-04-29T02:31:13.004321Z","steps":["trace[379675696] 'read index received' (duration: 370.428519ms)","trace[379675696] 'applied index is now lower than readState.Index' (duration: 10.736405ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:31:13.004489Z","caller":"traceutil/trace.go:171","msg":"trace[1092635213] transaction","detail":"{read_only:false; response_revision:17410; number_of_response:1; }","duration":"402.979282ms","start":"2026-04-29T02:31:12.601498Z","end":"2026-04-29T02:31:13.004477Z","steps":["trace[1092635213] 'process raft request' (duration: 402.562852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:31:13.004641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:31:12.601468Z","time spent":"403.052703ms","remote":"127.0.0.1:40488","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-04-29T02:31:13.004791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.625574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-29T02:31:13.004855Z","caller":"traceutil/trace.go:171","msg":"trace[1334697710] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17411; }","duration":"381.764798ms","start":"2026-04-29T02:31:12.623074Z","end":"2026-04-29T02:31:13.004839Z","steps":["trace[1334697710] 'agreement among raft nodes before linearized reading' (duration: 381.543293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:31:13.00489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:31:12.623043Z","time spent":"381.840399ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-29T02:31:13.00502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.754229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-29T02:31:13.005056Z","caller":"traceutil/trace.go:171","msg":"trace[1359196450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17411; }","duration":"123.838982ms","start":"2026-04-29T02:31:12.881205Z","end":"2026-04-29T02:31:13.005044Z","steps":["trace[1359196450] 'agreement among raft nodes before linearized reading' (duration: 123.689958ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:31:13.005103Z","caller":"traceutil/trace.go:171","msg":"trace[1322376423] transaction","detail":"{read_only:false; response_revision:17411; number_of_response:1; }","duration":"155.596375ms","start":"2026-04-29T02:31:12.849477Z","end":"2026-04-29T02:31:13.005073Z","steps":["trace[1322376423] 'process raft request' (duration: 154.772706ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:31:47.872903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15339} {"level":"info","ts":"2026-04-29T02:31:47.975977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15339,"took":"98.809956ms","hash":3769623198,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":33824768,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-29T02:31:47.976049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3769623198,"revision":15339,"compact-revision":12838} {"level":"info","ts":"2026-04-29T02:35:06.409048Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T02:35:06.413439Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-29T02:35:06.413523Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-29T02:36:40.508197Z","caller":"traceutil/trace.go:171","msg":"trace[802424110] transaction","detail":"{read_only:false; response_revision:19989; number_of_response:1; }","duration":"136.685376ms","start":"2026-04-29T02:36:40.371443Z","end":"2026-04-29T02:36:40.508128Z","steps":["trace[802424110] 'process raft request' (duration: 136.45108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:36:46.517309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.206451ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-29T02:36:46.517424Z","caller":"traceutil/trace.go:171","msg":"trace[851364600] linearizableReadLoop","detail":"{readStateIndex:20700; appliedIndex:20699; }","duration":"146.650033ms","start":"2026-04-29T02:36:46.370754Z","end":"2026-04-29T02:36:46.517404Z","steps":["trace[851364600] 'read index received' (duration: 3.291918ms)","trace[851364600] 'applied index is now lower than readState.Index' (duration: 143.356405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:36:46.517507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.781366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:36:46.517552Z","caller":"traceutil/trace.go:171","msg":"trace[173071013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20020; }","duration":"146.868618ms","start":"2026-04-29T02:36:46.370669Z","end":"2026-04-29T02:36:46.517538Z","steps":["trace[173071013] 'agreement among raft nodes before linearized reading' (duration: 146.784076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:36:46.81475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.20645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-29T02:36:46.814846Z","caller":"traceutil/trace.go:171","msg":"trace[901657029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20021; }","duration":"124.391114ms","start":"2026-04-29T02:36:46.690434Z","end":"2026-04-29T02:36:46.814825Z","steps":["trace[901657029] 'range keys from in-memory index tree' (duration: 124.024386ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:36:46.96279Z","caller":"traceutil/trace.go:171","msg":"trace[323509339] linearizableReadLoop","detail":"{readStateIndex:20702; appliedIndex:20701; }","duration":"115.029831ms","start":"2026-04-29T02:36:46.847742Z","end":"2026-04-29T02:36:46.962772Z","steps":["trace[323509339] 'read index received' (duration: 114.849797ms)","trace[323509339] 'applied index is now lower than readState.Index' (duration: 179.404µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:36:46.962867Z","caller":"traceutil/trace.go:171","msg":"trace[779012402] transaction","detail":"{read_only:false; response_revision:20022; number_of_response:1; }","duration":"143.842586ms","start":"2026-04-29T02:36:46.818992Z","end":"2026-04-29T02:36:46.962835Z","steps":["trace[779012402] 'process raft request' (duration: 143.640591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:36:46.962939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.176466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-29T02:36:46.962968Z","caller":"traceutil/trace.go:171","msg":"trace[123588667] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:20022; }","duration":"115.260257ms","start":"2026-04-29T02:36:46.847698Z","end":"2026-04-29T02:36:46.962958Z","steps":["trace[123588667] 'agreement among raft nodes before linearized reading' (duration: 115.143665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:36:47.240454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.128622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:36:47.240542Z","caller":"traceutil/trace.go:171","msg":"trace[638815548] linearizableReadLoop","detail":"{readStateIndex:20703; appliedIndex:20702; }","duration":"211.470942ms","start":"2026-04-29T02:36:47.029059Z","end":"2026-04-29T02:36:47.240529Z","steps":["trace[638815548] 'read index received' (duration: 73.114286ms)","trace[638815548] 'applied index is now lower than readState.Index' (duration: 138.355806ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:36:47.240637Z","caller":"traceutil/trace.go:171","msg":"trace[1342900763] transaction","detail":"{read_only:false; response_revision:20023; number_of_response:1; }","duration":"240.272966ms","start":"2026-04-29T02:36:47.000332Z","end":"2026-04-29T02:36:47.240605Z","steps":["trace[1342900763] 'process raft request' (duration: 101.91524ms)","trace[1342900763] 'compare' (duration: 137.893965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:36:47.240737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.680928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-29T02:36:47.24079Z","caller":"traceutil/trace.go:171","msg":"trace[1955885521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20023; }","duration":"211.78455ms","start":"2026-04-29T02:36:47.028991Z","end":"2026-04-29T02:36:47.240776Z","steps":["trace[1955885521] 'agreement among raft nodes before linearized reading' (duration: 211.626626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:36:47.240821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.778247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-29T02:36:47.240849Z","caller":"traceutil/trace.go:171","msg":"trace[1634897966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20023; }","duration":"210.826078ms","start":"2026-04-29T02:36:47.030012Z","end":"2026-04-29T02:36:47.240838Z","steps":["trace[1634897966] 'agreement among raft nodes before linearized reading' (duration: 210.719115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:36:47.241499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.042421ms","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-04-29T02:36:47.241542Z","caller":"traceutil/trace.go:171","msg":"trace[795007961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20023; }","duration":"160.112353ms","start":"2026-04-29T02:36:47.08142Z","end":"2026-04-29T02:36:47.241532Z","steps":["trace[795007961] 'agreement among raft nodes before linearized reading' (duration: 159.490138ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:36:47.478698Z","caller":"traceutil/trace.go:171","msg":"trace[384178911] linearizableReadLoop","detail":"{readStateIndex:20708; appliedIndex:20707; }","duration":"108.067077ms","start":"2026-04-29T02:36:47.370609Z","end":"2026-04-29T02:36:47.478676Z","steps":["trace[384178911] 'read index received' (duration: 107.811221ms)","trace[384178911] 'applied index is now lower than readState.Index' (duration: 254.656µs)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:36:47.478851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.22781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:36:47.479033Z","caller":"traceutil/trace.go:171","msg":"trace[1147702112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20028; }","duration":"108.442576ms","start":"2026-04-29T02:36:47.370575Z","end":"2026-04-29T02:36:47.479017Z","steps":["trace[1147702112] 'agreement among raft nodes before linearized reading' (duration: 108.20798ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:36:47.478842Z","caller":"traceutil/trace.go:171","msg":"trace[182057995] transaction","detail":"{read_only:false; response_revision:20028; number_of_response:1; }","duration":"154.592791ms","start":"2026-04-29T02:36:47.324218Z","end":"2026-04-29T02:36:47.478811Z","steps":["trace[182057995] 'process raft request' (duration: 154.197962ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:36:47.879648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17901} {"level":"info","ts":"2026-04-29T02:36:48.008627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17901,"took":"123.663147ms","hash":365055398,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":29458432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-29T02:36:48.008683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":365055398,"revision":17901,"compact-revision":15339} {"level":"info","ts":"2026-04-29T02:40:30.373713Z","caller":"traceutil/trace.go:171","msg":"trace[120835132] transaction","detail":"{read_only:false; response_revision:22383; number_of_response:1; }","duration":"206.248179ms","start":"2026-04-29T02:40:30.167403Z","end":"2026-04-29T02:40:30.373651Z","steps":["trace[120835132] 'process raft request' (duration: 206.073584ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:40:30.501923Z","caller":"traceutil/trace.go:171","msg":"trace[1859587300] transaction","detail":"{read_only:false; response_revision:22384; number_of_response:1; }","duration":"308.272821ms","start":"2026-04-29T02:40:30.193618Z","end":"2026-04-29T02:40:30.501891Z","steps":["trace[1859587300] 'process raft request' (duration: 307.991855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:40:30.502193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:40:30.193595Z","time spent":"308.495616ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:40:30.502608Z","caller":"traceutil/trace.go:171","msg":"trace[1444708838] transaction","detail":"{read_only:false; response_revision:22385; number_of_response:1; }","duration":"232.243666ms","start":"2026-04-29T02:40:30.270344Z","end":"2026-04-29T02:40:30.502588Z","steps":["trace[1444708838] 'process raft request' (duration: 231.478188ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:40:30.502858Z","caller":"traceutil/trace.go:171","msg":"trace[894294402] linearizableReadLoop","detail":"{readStateIndex:23131; appliedIndex:23129; }","duration":"253.690335ms","start":"2026-04-29T02:40:30.248835Z","end":"2026-04-29T02:40:30.502525Z","steps":["trace[894294402] 'read index received' (duration: 124.647771ms)","trace[894294402] 'applied index is now lower than readState.Index' (duration: 129.041134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:40:30.502943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.084384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-04-29T02:40:30.503053Z","caller":"traceutil/trace.go:171","msg":"trace[3943544] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:22385; }","duration":"254.242038ms","start":"2026-04-29T02:40:30.248786Z","end":"2026-04-29T02:40:30.503028Z","steps":["trace[3943544] 'agreement among raft nodes before linearized reading' (duration: 253.986262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:40:30.503436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.837444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:40:30.503568Z","caller":"traceutil/trace.go:171","msg":"trace[1412783450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22385; }","duration":"132.986238ms","start":"2026-04-29T02:40:30.370547Z","end":"2026-04-29T02:40:30.503534Z","steps":["trace[1412783450] 'agreement among raft nodes before linearized reading' (duration: 132.838665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:40:30.50409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.892168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417543"} {"level":"info","ts":"2026-04-29T02:40:30.504159Z","caller":"traceutil/trace.go:171","msg":"trace[1760553513] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:22385; }","duration":"103.99844ms","start":"2026-04-29T02:40:30.400142Z","end":"2026-04-29T02:40:30.50414Z","steps":["trace[1760553513] 'agreement among raft nodes before linearized reading' (duration: 103.727724ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:41:47.886187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20029} {"level":"info","ts":"2026-04-29T02:41:47.987023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20029,"took":"97.018293ms","hash":334069140,"current-db-size-bytes":57806848,"current-db-size":"58 MB","current-db-size-in-use-bytes":41422848,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-29T02:41:47.987088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":334069140,"revision":20029,"compact-revision":17901} {"level":"info","ts":"2026-04-29T02:43:52.563389Z","caller":"traceutil/trace.go:171","msg":"trace[67192254] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"176.981442ms","start":"2026-04-29T02:43:52.386368Z","end":"2026-04-29T02:43:52.563349Z","steps":["trace[67192254] 'process raft request' (duration: 150.007561ms)","trace[67192254] 'compare' (duration: 26.753316ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:43:52.86626Z","caller":"traceutil/trace.go:171","msg":"trace[334732507] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"276.2853ms","start":"2026-04-29T02:43:52.589946Z","end":"2026-04-29T02:43:52.866231Z","steps":["trace[334732507] 'process raft request' (duration: 276.131426ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:53.156718Z","caller":"traceutil/trace.go:171","msg":"trace[1600385198] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"176.471501ms","start":"2026-04-29T02:43:52.980214Z","end":"2026-04-29T02:43:53.156686Z","steps":["trace[1600385198] 'process raft request' (duration: 136.643835ms)","trace[1600385198] 'compare' (duration: 39.538769ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:43:57.400207Z","caller":"traceutil/trace.go:171","msg":"trace[1907624473] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"108.049957ms","start":"2026-04-29T02:43:57.292131Z","end":"2026-04-29T02:43:57.400181Z","steps":["trace[1907624473] 'process raft request' (duration: 107.668558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:57.805449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.036236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:43:57.805635Z","caller":"traceutil/trace.go:171","msg":"trace[300686415] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"137.341451ms","start":"2026-04-29T02:43:57.668277Z","end":"2026-04-29T02:43:57.805619Z","steps":["trace[300686415] 'process raft request' (duration: 137.279539ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:57.80575Z","caller":"traceutil/trace.go:171","msg":"trace[1384003744] transaction","detail":"{read_only:false; response_revision:24476; number_of_response:1; }","duration":"334.500133ms","start":"2026-04-29T02:43:57.471209Z","end":"2026-04-29T02:43:57.80571Z","steps":["trace[1384003744] 'process raft request' (duration: 95.125159ms)","trace[1384003744] 'compare' (duration: 238.412601ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:43:57.805871Z","caller":"traceutil/trace.go:171","msg":"trace[620424863] linearizableReadLoop","detail":"{readStateIndex:25278; appliedIndex:25277; }","duration":"177.345821ms","start":"2026-04-29T02:43:57.628514Z","end":"2026-04-29T02:43:57.80586Z","steps":["trace[620424863] 'read index received' (duration: 68.972µs)","trace[620424863] 'applied index is now lower than readState.Index' (duration: 177.275769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:43:57.805916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.471183Z","time spent":"334.661577ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:43:57.806104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.592598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-29T02:43:57.806142Z","caller":"traceutil/trace.go:171","msg":"trace[1663264049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24477; }","duration":"177.66609ms","start":"2026-04-29T02:43:57.628466Z","end":"2026-04-29T02:43:57.806132Z","steps":["trace[1663264049] 'agreement among raft nodes before linearized reading' (duration: 177.517735ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:58.159022Z","caller":"traceutil/trace.go:171","msg":"trace[2039555803] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"347.900213ms","start":"2026-04-29T02:43:57.81109Z","end":"2026-04-29T02:43:58.15899Z","steps":["trace[2039555803] 'process raft request' (duration: 347.693207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:58.159221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.811068Z","time spent":"348.046337ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:43:58.349583Z","caller":"traceutil/trace.go:171","msg":"trace[635680619] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"537.577906ms","start":"2026-04-29T02:43:57.811943Z","end":"2026-04-29T02:43:58.349521Z","steps":["trace[635680619] 'process raft request' (duration: 518.861311ms)","trace[635680619] 'compare' (duration: 18.449098ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:43:58.349755Z","caller":"traceutil/trace.go:171","msg":"trace[1343845506] linearizableReadLoop","detail":"{readStateIndex:25283; appliedIndex:25279; }","duration":"535.20291ms","start":"2026-04-29T02:43:57.814524Z","end":"2026-04-29T02:43:58.349727Z","steps":["trace[1343845506] 'read index received' (duration: 344.175893ms)","trace[1343845506] 'applied index is now lower than readState.Index' (duration: 191.025857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:43:58.349838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.811922Z","time spent":"537.770711ms","remote":"127.0.0.1:40488","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-04-29T02:43:58.349977Z","caller":"traceutil/trace.go:171","msg":"trace[392690821] transaction","detail":"{read_only:false; response_revision:24483; number_of_response:1; }","duration":"477.812187ms","start":"2026-04-29T02:43:57.87213Z","end":"2026-04-29T02:43:58.349942Z","steps":["trace[392690821] 'process raft request' (duration: 477.424388ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:58.350011Z","caller":"traceutil/trace.go:171","msg":"trace[1695207463] transaction","detail":"{read_only:false; response_revision:24485; number_of_response:1; }","duration":"196.085697ms","start":"2026-04-29T02:43:58.153895Z","end":"2026-04-29T02:43:58.349981Z","steps":["trace[1695207463] 'process raft request' (duration: 195.758399ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:58.350027Z","caller":"traceutil/trace.go:171","msg":"trace[616488502] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"537.837233ms","start":"2026-04-29T02:43:57.812135Z","end":"2026-04-29T02:43:58.349972Z","steps":["trace[616488502] 'process raft request' (duration: 537.341151ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:58.350086Z","caller":"traceutil/trace.go:171","msg":"trace[1789225791] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"538.116039ms","start":"2026-04-29T02:43:57.811955Z","end":"2026-04-29T02:43:58.350071Z","steps":["trace[1789225791] 'process raft request' (duration: 537.472403ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:58.35011Z","caller":"traceutil/trace.go:171","msg":"trace[554846779] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"194.877769ms","start":"2026-04-29T02:43:58.155219Z","end":"2026-04-29T02:43:58.350097Z","steps":["trace[554846779] 'process raft request' (duration: 194.467509ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:58.350165Z","caller":"traceutil/trace.go:171","msg":"trace[2021008622] transaction","detail":"{read_only:false; response_revision:24484; number_of_response:1; }","duration":"356.132437ms","start":"2026-04-29T02:43:57.994024Z","end":"2026-04-29T02:43:58.350157Z","steps":["trace[2021008622] 'process raft request' (duration: 355.589084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:58.350156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.811941Z","time spent":"538.17979ms","remote":"127.0.0.1:40488","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-04-29T02:43:58.350253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.994003Z","time spent":"356.183588ms","remote":"127.0.0.1:40488","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-04-29T02:43:58.350269Z","caller":"traceutil/trace.go:171","msg":"trace[1460223651] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"534.618746ms","start":"2026-04-29T02:43:57.815638Z","end":"2026-04-29T02:43:58.350256Z","steps":["trace[1460223651] 'process raft request' (duration: 533.872049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:58.350283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.812128Z","time spent":"537.978586ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:43:58.350066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.496237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6a7d078d-98dd-48c2-99f4-edb5dc2e42e7\" ","response":"range_response_count:1 size:1318"} {"level":"warn","ts":"2026-04-29T02:43:58.350326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.81563Z","time spent":"534.661697ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:43:58.350335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.872106Z","time spent":"477.958061ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:43:58.350382Z","caller":"traceutil/trace.go:171","msg":"trace[1566515482] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6a7d078d-98dd-48c2-99f4-edb5dc2e42e7; range_end:; response_count:1; response_revision:24486; }","duration":"535.847434ms","start":"2026-04-29T02:43:57.814501Z","end":"2026-04-29T02:43:58.350349Z","steps":["trace[1566515482] 'agreement among raft nodes before linearized reading' (duration: 535.332862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:58.350503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:57.814494Z","time spent":"535.980127ms","remote":"127.0.0.1:40380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1339,"request content":"key:\"/registry/persistentvolumes/pvc-6a7d078d-98dd-48c2-99f4-edb5dc2e42e7\" "} {"level":"warn","ts":"2026-04-29T02:43:58.929484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.532965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:43:58.929577Z","caller":"traceutil/trace.go:171","msg":"trace[1742709486] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24487; }","duration":"110.675509ms","start":"2026-04-29T02:43:58.818878Z","end":"2026-04-29T02:43:58.929553Z","steps":["trace[1742709486] 'count revisions from in-memory index tree' (duration: 110.472274ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:43:59.768829Z","caller":"traceutil/trace.go:171","msg":"trace[479850995] linearizableReadLoop","detail":"{readStateIndex:25291; appliedIndex:25290; }","duration":"295.422525ms","start":"2026-04-29T02:43:59.473376Z","end":"2026-04-29T02:43:59.768798Z","steps":["trace[479850995] 'read index received' (duration: 295.109158ms)","trace[479850995] 'applied index is now lower than readState.Index' (duration: 312.077µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:43:59.76892Z","caller":"traceutil/trace.go:171","msg":"trace[1145834997] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"301.607922ms","start":"2026-04-29T02:43:59.467277Z","end":"2026-04-29T02:43:59.768884Z","steps":["trace[1145834997] 'process raft request' (duration: 301.320995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:59.769088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:43:59.467264Z","time spent":"301.729255ms","remote":"127.0.0.1:40488","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-04-29T02:43:59.769223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.537513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:43:59.769334Z","caller":"traceutil/trace.go:171","msg":"trace[139588384] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24489; }","duration":"195.722588ms","start":"2026-04-29T02:43:59.573583Z","end":"2026-04-29T02:43:59.769306Z","steps":["trace[139588384] 'agreement among raft nodes before linearized reading' (duration: 195.478662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:43:59.769546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.628899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:43:59.769617Z","caller":"traceutil/trace.go:171","msg":"trace[745070303] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24489; }","duration":"296.256345ms","start":"2026-04-29T02:43:59.473344Z","end":"2026-04-29T02:43:59.7696Z","steps":["trace[745070303] 'agreement among raft nodes before linearized reading' (duration: 295.60489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.081902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.356936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:00.082075Z","caller":"traceutil/trace.go:171","msg":"trace[1361991523] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"269.018949ms","start":"2026-04-29T02:43:59.813012Z","end":"2026-04-29T02:44:00.082031Z","steps":["trace[1361991523] 'process raft request' (duration: 114.444128ms)","trace[1361991523] 'compare' (duration: 154.011608ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:00.082459Z","caller":"traceutil/trace.go:171","msg":"trace[1266718763] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"269.357236ms","start":"2026-04-29T02:43:59.81307Z","end":"2026-04-29T02:44:00.082428Z","steps":["trace[1266718763] 'process raft request' (duration: 268.925746ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:00.082616Z","caller":"traceutil/trace.go:171","msg":"trace[233030604] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"199.227151ms","start":"2026-04-29T02:43:59.883364Z","end":"2026-04-29T02:44:00.082591Z","steps":["trace[233030604] 'process raft request' (duration: 198.972585ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:00.08266Z","caller":"traceutil/trace.go:171","msg":"trace[1711465526] linearizableReadLoop","detail":"{readStateIndex:25294; appliedIndex:25291; }","duration":"190.689959ms","start":"2026-04-29T02:43:59.891958Z","end":"2026-04-29T02:44:00.082648Z","steps":["trace[1711465526] 'read index received' (duration: 35.455082ms)","trace[1711465526] 'applied index is now lower than readState.Index' (duration: 155.232696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:00.082865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.884953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:00.082899Z","caller":"traceutil/trace.go:171","msg":"trace[363273479] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24493; }","duration":"190.990136ms","start":"2026-04-29T02:43:59.891896Z","end":"2026-04-29T02:44:00.082887Z","steps":["trace[363273479] 'agreement among raft nodes before linearized reading' (duration: 190.880344ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:00.499104Z","caller":"traceutil/trace.go:171","msg":"trace[1112168234] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"331.462481ms","start":"2026-04-29T02:44:00.167615Z","end":"2026-04-29T02:44:00.499078Z","steps":["trace[1112168234] 'process raft request' (duration: 331.302397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.499249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.167602Z","time spent":"331.576934ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:00.517898Z","caller":"traceutil/trace.go:171","msg":"trace[1530933649] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"154.245604ms","start":"2026-04-29T02:44:00.363633Z","end":"2026-04-29T02:44:00.517878Z","steps":["trace[1530933649] 'process raft request' (duration: 154.121251ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:00.517918Z","caller":"traceutil/trace.go:171","msg":"trace[281492752] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"160.937163ms","start":"2026-04-29T02:44:00.356935Z","end":"2026-04-29T02:44:00.517872Z","steps":["trace[281492752] 'process raft request' (duration: 160.639025ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:00.517877Z","caller":"traceutil/trace.go:171","msg":"trace[197114716] linearizableReadLoop","detail":"{readStateIndex:25297; appliedIndex:25295; }","duration":"160.861881ms","start":"2026-04-29T02:44:00.356983Z","end":"2026-04-29T02:44:00.517845Z","steps":["trace[197114716] 'read index received' (duration: 141.936721ms)","trace[197114716] 'applied index is now lower than readState.Index' (duration: 18.9238ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:00.518167Z","caller":"traceutil/trace.go:171","msg":"trace[472042212] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"149.44681ms","start":"2026-04-29T02:44:00.368709Z","end":"2026-04-29T02:44:00.518156Z","steps":["trace[472042212] 'process raft request' (duration: 149.089882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.51818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.172218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-29T02:44:00.518245Z","caller":"traceutil/trace.go:171","msg":"trace[976658467] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24497; }","duration":"161.302001ms","start":"2026-04-29T02:44:00.356925Z","end":"2026-04-29T02:44:00.518227Z","steps":["trace[976658467] 'agreement among raft nodes before linearized reading' (duration: 161.005954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.51842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.409712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T02:44:00.518446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.646331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-29T02:44:00.518459Z","caller":"traceutil/trace.go:171","msg":"trace[862013100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24497; }","duration":"147.507764ms","start":"2026-04-29T02:44:00.37094Z","end":"2026-04-29T02:44:00.518448Z","steps":["trace[862013100] 'agreement among raft nodes before linearized reading' (duration: 147.410202ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:00.518481Z","caller":"traceutil/trace.go:171","msg":"trace[1955859169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24497; }","duration":"159.685352ms","start":"2026-04-29T02:44:00.358787Z","end":"2026-04-29T02:44:00.518472Z","steps":["trace[1955859169] 'agreement among raft nodes before linearized reading' (duration: 159.525078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.518518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.557684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-29T02:44:00.518613Z","caller":"traceutil/trace.go:171","msg":"trace[949795636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24497; }","duration":"152.713848ms","start":"2026-04-29T02:44:00.365877Z","end":"2026-04-29T02:44:00.518591Z","steps":["trace[949795636] 'agreement among raft nodes before linearized reading' (duration: 152.422111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.518784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.67804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T02:44:00.521006Z","caller":"traceutil/trace.go:171","msg":"trace[293502135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24497; }","duration":"163.77114ms","start":"2026-04-29T02:44:00.356975Z","end":"2026-04-29T02:44:00.520746Z","steps":["trace[293502135] 'agreement among raft nodes before linearized reading' (duration: 161.300921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:00.842005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.668835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:00.842172Z","caller":"traceutil/trace.go:171","msg":"trace[202516876] transaction","detail":"{read_only:false; response_revision:24498; number_of_response:1; }","duration":"316.738991ms","start":"2026-04-29T02:44:00.525412Z","end":"2026-04-29T02:44:00.842151Z","steps":["trace[202516876] 'process raft request' (duration: 155.827791ms)","trace[202516876] 'compare' (duration: 160.224704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:00.842227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.525379Z","time spent":"316.824354ms","remote":"127.0.0.1:40488","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-04-29T02:44:01.051444Z","caller":"traceutil/trace.go:171","msg":"trace[1292861208] linearizableReadLoop","detail":"{readStateIndex:25303; appliedIndex:25300; }","duration":"367.63362ms","start":"2026-04-29T02:44:00.683792Z","end":"2026-04-29T02:44:01.051426Z","steps":["trace[1292861208] 'read index received' (duration: 367.420315ms)","trace[1292861208] 'applied index is now lower than readState.Index' (duration: 212.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:01.05163Z","caller":"traceutil/trace.go:171","msg":"trace[996454959] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"521.259058ms","start":"2026-04-29T02:44:00.530363Z","end":"2026-04-29T02:44:01.051622Z","steps":["trace[996454959] 'process raft request' (duration: 521.033913ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:01.051688Z","caller":"traceutil/trace.go:171","msg":"trace[360469133] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"525.780356ms","start":"2026-04-29T02:44:00.525842Z","end":"2026-04-29T02:44:01.051622Z","steps":["trace[360469133] 'process raft request' (duration: 525.435888ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:01.051646Z","caller":"traceutil/trace.go:171","msg":"trace[1018250735] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"525.92355ms","start":"2026-04-29T02:44:00.525646Z","end":"2026-04-29T02:44:01.05157Z","steps":["trace[1018250735] 'process raft request' (duration: 525.48352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.05181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.530357Z","time spent":"521.28764ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:01.051938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.525834Z","time spent":"525.971491ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:01.051945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.148422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-04-29T02:44:01.051963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.52562Z","time spent":"526.219766ms","remote":"127.0.0.1:40488","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-04-29T02:44:01.05209Z","caller":"traceutil/trace.go:171","msg":"trace[1255378258] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24501; }","duration":"368.223454ms","start":"2026-04-29T02:44:00.68377Z","end":"2026-04-29T02:44:01.051994Z","steps":["trace[1255378258] 'agreement among raft nodes before linearized reading' (duration: 368.05206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.052137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.683695Z","time spent":"368.431659ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-29T02:44:01.108936Z","caller":"traceutil/trace.go:171","msg":"trace[1973845006] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"417.66338ms","start":"2026-04-29T02:44:00.691231Z","end":"2026-04-29T02:44:01.108894Z","steps":["trace[1973845006] 'process raft request' (duration: 417.399403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.109008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.22058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:01.109098Z","caller":"traceutil/trace.go:171","msg":"trace[1950224684] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24502; }","duration":"100.387664ms","start":"2026-04-29T02:44:01.00869Z","end":"2026-04-29T02:44:01.109077Z","steps":["trace[1950224684] 'agreement among raft nodes before linearized reading' (duration: 100.19492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.10911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.975545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:01.109256Z","caller":"traceutil/trace.go:171","msg":"trace[1920900853] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24502; }","duration":"247.229652ms","start":"2026-04-29T02:44:00.861997Z","end":"2026-04-29T02:44:01.109227Z","steps":["trace[1920900853] 'agreement among raft nodes before linearized reading' (duration: 246.910334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.109163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:00.691203Z","time spent":"417.838753ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:01.300639Z","caller":"traceutil/trace.go:171","msg":"trace[1985515312] linearizableReadLoop","detail":"{readStateIndex:25305; appliedIndex:25304; }","duration":"191.740183ms","start":"2026-04-29T02:44:01.108857Z","end":"2026-04-29T02:44:01.300597Z","steps":["trace[1985515312] 'read index received' (duration: 116.891765ms)","trace[1985515312] 'applied index is now lower than readState.Index' (duration: 74.846548ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:01.300728Z","caller":"traceutil/trace.go:171","msg":"trace[1058828825] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"241.461584ms","start":"2026-04-29T02:44:01.059221Z","end":"2026-04-29T02:44:01.300683Z","steps":["trace[1058828825] 'process raft request' (duration: 166.618447ms)","trace[1058828825] 'compare' (duration: 74.55341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:01.301002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.20584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-29T02:44:01.301053Z","caller":"traceutil/trace.go:171","msg":"trace[836447899] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:24503; }","duration":"212.353814ms","start":"2026-04-29T02:44:01.088686Z","end":"2026-04-29T02:44:01.30104Z","steps":["trace[836447899] 'agreement among raft nodes before linearized reading' (duration: 212.122678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.30126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.913474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T02:44:01.301314Z","caller":"traceutil/trace.go:171","msg":"trace[944949125] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24503; }","duration":"119.018357ms","start":"2026-04-29T02:44:01.182279Z","end":"2026-04-29T02:44:01.301298Z","steps":["trace[944949125] 'agreement among raft nodes before linearized reading' (duration: 118.891494ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:01.503598Z","caller":"traceutil/trace.go:171","msg":"trace[704267960] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"189.634742ms","start":"2026-04-29T02:44:01.313921Z","end":"2026-04-29T02:44:01.503556Z","steps":["trace[704267960] 'process raft request' (duration: 165.545901ms)","trace[704267960] 'compare' (duration: 23.881927ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:01.665732Z","caller":"traceutil/trace.go:171","msg":"trace[472206049] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"274.739504ms","start":"2026-04-29T02:44:01.390961Z","end":"2026-04-29T02:44:01.665701Z","steps":["trace[472206049] 'process raft request' (duration: 274.526049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:01.98508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.664236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-29T02:44:01.985165Z","caller":"traceutil/trace.go:171","msg":"trace[2146136465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24505; }","duration":"211.802479ms","start":"2026-04-29T02:44:01.773343Z","end":"2026-04-29T02:44:01.985145Z","steps":["trace[2146136465] 'range keys from in-memory index tree' (duration: 211.473761ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:02.208055Z","caller":"traceutil/trace.go:171","msg":"trace[922404788] transaction","detail":"{read_only:false; response_revision:24508; number_of_response:1; }","duration":"112.810179ms","start":"2026-04-29T02:44:02.095209Z","end":"2026-04-29T02:44:02.208019Z","steps":["trace[922404788] 'process raft request' (duration: 112.756648ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:02.208447Z","caller":"traceutil/trace.go:171","msg":"trace[722344584] transaction","detail":"{read_only:false; response_revision:24507; number_of_response:1; }","duration":"118.400551ms","start":"2026-04-29T02:44:02.090035Z","end":"2026-04-29T02:44:02.208436Z","steps":["trace[722344584] 'process raft request' (duration: 73.937695ms)","trace[722344584] 'compare' (duration: 43.687548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:02.506018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.929547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:02.506129Z","caller":"traceutil/trace.go:171","msg":"trace[1380893411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24508; }","duration":"136.079491ms","start":"2026-04-29T02:44:02.370028Z","end":"2026-04-29T02:44:02.506107Z","steps":["trace[1380893411] 'range keys from in-memory index tree' (duration: 135.740093ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:02.889391Z","caller":"traceutil/trace.go:171","msg":"trace[415008216] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"362.703413ms","start":"2026-04-29T02:44:02.526666Z","end":"2026-04-29T02:44:02.889369Z","steps":["trace[415008216] 'process raft request' (duration: 362.59632ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:02.889416Z","caller":"traceutil/trace.go:171","msg":"trace[809794132] transaction","detail":"{read_only:false; response_revision:24510; number_of_response:1; }","duration":"363.300038ms","start":"2026-04-29T02:44:02.526083Z","end":"2026-04-29T02:44:02.889383Z","steps":["trace[809794132] 'process raft request' (duration: 362.98225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:02.889538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:02.526655Z","time spent":"362.805706ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:02.88955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:02.526056Z","time spent":"363.426231ms","remote":"127.0.0.1:40488","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-04-29T02:44:02.889651Z","caller":"traceutil/trace.go:171","msg":"trace[1642377897] linearizableReadLoop","detail":"{readStateIndex:25315; appliedIndex:25311; }","duration":"347.094062ms","start":"2026-04-29T02:44:02.542522Z","end":"2026-04-29T02:44:02.889616Z","steps":["trace[1642377897] 'read index received' (duration: 18.1076ms)","trace[1642377897] 'applied index is now lower than readState.Index' (duration: 328.983702ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:02.889741Z","caller":"traceutil/trace.go:171","msg":"trace[659005015] transaction","detail":"{read_only:false; response_revision:24512; number_of_response:1; }","duration":"362.57015ms","start":"2026-04-29T02:44:02.527129Z","end":"2026-04-29T02:44:02.889699Z","steps":["trace[659005015] 'process raft request' (duration: 362.194541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:02.889899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.686315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T02:44:02.889943Z","caller":"traceutil/trace.go:171","msg":"trace[796570992] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24512; }","duration":"181.828009ms","start":"2026-04-29T02:44:02.7081Z","end":"2026-04-29T02:44:02.889928Z","steps":["trace[796570992] 'agreement among raft nodes before linearized reading' (duration: 181.693015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:02.889956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:02.527105Z","time spent":"362.720833ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:02.889949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.40262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-04-29T02:44:02.890021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.801311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-04-29T02:44:02.890025Z","caller":"traceutil/trace.go:171","msg":"trace[730616961] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24512; }","duration":"347.527192ms","start":"2026-04-29T02:44:02.542481Z","end":"2026-04-29T02:44:02.890008Z","steps":["trace[730616961] 'agreement among raft nodes before linearized reading' (duration: 347.271886ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:02.890053Z","caller":"traceutil/trace.go:171","msg":"trace[1481824693] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24512; }","duration":"110.875692ms","start":"2026-04-29T02:44:02.779169Z","end":"2026-04-29T02:44:02.890044Z","steps":["trace[1481824693] 'agreement among raft nodes before linearized reading' (duration: 110.76341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:02.890064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:02.542467Z","time spent":"347.589835ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-29T02:44:03.074838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.788606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:03.075091Z","caller":"traceutil/trace.go:171","msg":"trace[246022036] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"178.981209ms","start":"2026-04-29T02:44:02.896092Z","end":"2026-04-29T02:44:03.075073Z","steps":["trace[246022036] 'process raft request' (duration: 178.842666ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:03.07528Z","caller":"traceutil/trace.go:171","msg":"trace[167535172] transaction","detail":"{read_only:false; response_revision:24513; number_of_response:1; }","duration":"179.133493ms","start":"2026-04-29T02:44:02.896097Z","end":"2026-04-29T02:44:03.075231Z","steps":["trace[167535172] 'process raft request' (duration: 44.842875ms)","trace[167535172] 'compare' (duration: 133.361036ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:03.368936Z","caller":"traceutil/trace.go:171","msg":"trace[818603956] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"252.799703ms","start":"2026-04-29T02:44:03.116113Z","end":"2026-04-29T02:44:03.368912Z","steps":["trace[818603956] 'process raft request' (duration: 252.745462ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:03.36916Z","caller":"traceutil/trace.go:171","msg":"trace[1101510468] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"286.839841ms","start":"2026-04-29T02:44:03.082286Z","end":"2026-04-29T02:44:03.369126Z","steps":["trace[1101510468] 'process raft request' (duration: 286.330719ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:03.369203Z","caller":"traceutil/trace.go:171","msg":"trace[268302100] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"279.163589ms","start":"2026-04-29T02:44:03.090031Z","end":"2026-04-29T02:44:03.369194Z","steps":["trace[268302100] 'process raft request' (duration: 278.79138ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:03.374472Z","caller":"traceutil/trace.go:171","msg":"trace[1249849968] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"133.010908ms","start":"2026-04-29T02:44:03.241438Z","end":"2026-04-29T02:44:03.374449Z","steps":["trace[1249849968] 'process raft request' (duration: 132.827244ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:11.113479Z","caller":"traceutil/trace.go:171","msg":"trace[1913014983] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"195.834811ms","start":"2026-04-29T02:44:10.917618Z","end":"2026-04-29T02:44:11.113453Z","steps":["trace[1913014983] 'process raft request' (duration: 195.649387ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:11.269184Z","caller":"traceutil/trace.go:171","msg":"trace[346842152] linearizableReadLoop","detail":"{readStateIndex:25374; appliedIndex:25371; }","duration":"192.521891ms","start":"2026-04-29T02:44:11.076636Z","end":"2026-04-29T02:44:11.269157Z","steps":["trace[346842152] 'read index received' (duration: 36.794363ms)","trace[346842152] 'applied index is now lower than readState.Index' (duration: 155.726188ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:11.269611Z","caller":"traceutil/trace.go:171","msg":"trace[162000304] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"340.910066ms","start":"2026-04-29T02:44:10.928682Z","end":"2026-04-29T02:44:11.269592Z","steps":["trace[162000304] 'process raft request' (duration: 340.223989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:11.269765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:10.92866Z","time spent":"341.007809ms","remote":"127.0.0.1:40488","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-04-29T02:44:11.270047Z","caller":"traceutil/trace.go:171","msg":"trace[1455045197] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"339.205705ms","start":"2026-04-29T02:44:10.930819Z","end":"2026-04-29T02:44:11.270025Z","steps":["trace[1455045197] 'process raft request' (duration: 338.286393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:11.270143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:10.930806Z","time spent":"339.289457ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:11.270432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.796202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T02:44:11.270515Z","caller":"traceutil/trace.go:171","msg":"trace[1549063642] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24570; }","duration":"193.914675ms","start":"2026-04-29T02:44:11.076589Z","end":"2026-04-29T02:44:11.270503Z","steps":["trace[1549063642] 'agreement among raft nodes before linearized reading' (duration: 193.74013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:11.270867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.473423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-29T02:44:11.270915Z","caller":"traceutil/trace.go:171","msg":"trace[1996001647] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:24570; }","duration":"182.544275ms","start":"2026-04-29T02:44:11.088361Z","end":"2026-04-29T02:44:11.270905Z","steps":["trace[1996001647] 'agreement among raft nodes before linearized reading' (duration: 182.35472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:11.271233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.984176ms","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-04-29T02:44:11.271279Z","caller":"traceutil/trace.go:171","msg":"trace[1916304603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24570; }","duration":"159.083448ms","start":"2026-04-29T02:44:11.112186Z","end":"2026-04-29T02:44:11.271269Z","steps":["trace[1916304603] 'agreement among raft nodes before linearized reading' (duration: 158.975956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:11.786295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.272826Z","time spent":"513.460723ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-29T02:44:12.414066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.362955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:12.414438Z","caller":"traceutil/trace.go:171","msg":"trace[297312451] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"1.139337156s","start":"2026-04-29T02:44:11.275046Z","end":"2026-04-29T02:44:12.414383Z","steps":["trace[297312451] 'process raft request' (duration: 768.489039ms)","trace[297312451] 'compare' (duration: 369.954996ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:12.414495Z","caller":"traceutil/trace.go:171","msg":"trace[614043616] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"1.017468082s","start":"2026-04-29T02:44:11.397012Z","end":"2026-04-29T02:44:12.41448Z","steps":["trace[614043616] 'process raft request' (duration: 1.017310388s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:12.414553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.275024Z","time spent":"1.139477409s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:12.4146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.396984Z","time spent":"1.017579405s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:12.414939Z","caller":"traceutil/trace.go:171","msg":"trace[1404527521] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"855.426024ms","start":"2026-04-29T02:44:11.559482Z","end":"2026-04-29T02:44:12.414908Z","steps":["trace[1404527521] 'process raft request' (duration: 854.927622ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:12.415018Z","caller":"traceutil/trace.go:171","msg":"trace[958512156] linearizableReadLoop","detail":"{readStateIndex:25377; appliedIndex:25374; }","duration":"1.095614109s","start":"2026-04-29T02:44:11.319373Z","end":"2026-04-29T02:44:12.414987Z","steps":["trace[958512156] 'read index received' (duration: 466.686954ms)","trace[958512156] 'applied index is now lower than readState.Index' (duration: 628.925255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:12.41511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.559457Z","time spent":"855.571998ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:12.415135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095754622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:12.415157Z","caller":"traceutil/trace.go:171","msg":"trace[341942306] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"1.139580862s","start":"2026-04-29T02:44:11.275362Z","end":"2026-04-29T02:44:12.414943Z","steps":["trace[341942306] 'process raft request' (duration: 1.138844995s)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:12.415194Z","caller":"traceutil/trace.go:171","msg":"trace[2105682319] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24574; }","duration":"1.095862645s","start":"2026-04-29T02:44:11.319312Z","end":"2026-04-29T02:44:12.415175Z","steps":["trace[2105682319] 'agreement among raft nodes before linearized reading' (duration: 1.095764972s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:12.415254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.319292Z","time spent":"1.095948148s","remote":"127.0.0.1:53890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-04-29T02:44:12.415299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.275355Z","time spent":"1.139853609s","remote":"127.0.0.1:40488","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-04-29T02:44:12.41557Z","caller":"traceutil/trace.go:171","msg":"trace[1663279268] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"628.121238ms","start":"2026-04-29T02:44:11.78742Z","end":"2026-04-29T02:44:12.415541Z","steps":["trace[1663279268] 'process raft request' (duration: 627.505732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:12.41587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.787396Z","time spent":"628.384733ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:12.915988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880586997065457,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-29T02:44:13.004774Z","caller":"traceutil/trace.go:171","msg":"trace[1436585204] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"749.121979ms","start":"2026-04-29T02:44:12.255608Z","end":"2026-04-29T02:44:13.00473Z","steps":["trace[1436585204] 'process raft request' (duration: 704.400577ms)","trace[1436585204] 'compare' (duration: 44.260791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:13.004951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.25558Z","time spent":"749.277603ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.005742Z","caller":"traceutil/trace.go:171","msg":"trace[46130392] linearizableReadLoop","detail":"{readStateIndex:25382; appliedIndex:25380; }","duration":"590.620726ms","start":"2026-04-29T02:44:12.415098Z","end":"2026-04-29T02:44:13.005719Z","steps":["trace[46130392] 'read index received' (duration: 544.85244ms)","trace[46130392] 'applied index is now lower than readState.Index' (duration: 45.766976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:13.005873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.930591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:13.005935Z","caller":"traceutil/trace.go:171","msg":"trace[665239176] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24577; }","duration":"951.027744ms","start":"2026-04-29T02:44:12.054891Z","end":"2026-04-29T02:44:13.005919Z","steps":["trace[665239176] 'agreement among raft nodes before linearized reading' (duration: 950.899611ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:13.005957Z","caller":"traceutil/trace.go:171","msg":"trace[1560961694] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"750.053821ms","start":"2026-04-29T02:44:12.255865Z","end":"2026-04-29T02:44:13.005919Z","steps":["trace[1560961694] 'process raft request' (duration: 749.747974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.006017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234111727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T02:44:13.006023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.789576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:13.00606Z","caller":"traceutil/trace.go:171","msg":"trace[2090119789] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24577; }","duration":"1.234187409s","start":"2026-04-29T02:44:11.771858Z","end":"2026-04-29T02:44:13.006045Z","steps":["trace[2090119789] 'agreement among raft nodes before linearized reading' (duration: 1.234133168s)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:13.00606Z","caller":"traceutil/trace.go:171","msg":"trace[1926072430] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24577; }","duration":"904.863198ms","start":"2026-04-29T02:44:12.101185Z","end":"2026-04-29T02:44:13.006048Z","steps":["trace[1926072430] 'agreement among raft nodes before linearized reading' (duration: 904.814567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.006073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.25584Z","time spent":"750.174614ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:13.006195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576044776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-29T02:44:13.00597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.715643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-29T02:44:13.006231Z","caller":"traceutil/trace.go:171","msg":"trace[1724685668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24577; }","duration":"1.576133129s","start":"2026-04-29T02:44:11.430089Z","end":"2026-04-29T02:44:13.006222Z","steps":["trace[1724685668] 'agreement among raft nodes before linearized reading' (duration: 1.576048817s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.006116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.771826Z","time spent":"1.23426755s","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-29T02:44:13.006263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.430048Z","time spent":"1.576208342s","remote":"127.0.0.1:40488","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-04-29T02:44:13.00629Z","caller":"traceutil/trace.go:171","msg":"trace[1334585863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24577; }","duration":"958.054881ms","start":"2026-04-29T02:44:12.048185Z","end":"2026-04-29T02:44:13.00624Z","steps":["trace[1334585863] 'agreement among raft nodes before linearized reading' (duration: 957.665902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.00638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.04816Z","time spent":"958.198976ms","remote":"127.0.0.1:40488","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-04-29T02:44:13.006443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602835624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-29T02:44:13.006488Z","caller":"traceutil/trace.go:171","msg":"trace[671324233] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24577; }","duration":"1.602933567s","start":"2026-04-29T02:44:11.403545Z","end":"2026-04-29T02:44:13.006479Z","steps":["trace[671324233] 'agreement among raft nodes before linearized reading' (duration: 1.602823444s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.005977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.054879Z","time spent":"951.088805ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-29T02:44:13.006522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.403521Z","time spent":"1.602994328s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-29T02:44:13.006118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.101161Z","time spent":"904.94076ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-29T02:44:13.006613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.240038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T02:44:13.006666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.37056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-29T02:44:13.006661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.549616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-04-29T02:44:13.006789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635463369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:13.006844Z","caller":"traceutil/trace.go:171","msg":"trace[1705414741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24577; }","duration":"1.635563091s","start":"2026-04-29T02:44:11.371269Z","end":"2026-04-29T02:44:13.006832Z","steps":["trace[1705414741] 'agreement among raft nodes before linearized reading' (duration: 1.635481389s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.006889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:11.371241Z","time spent":"1.635637792s","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-29T02:44:13.006793Z","caller":"traceutil/trace.go:171","msg":"trace[946565139] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24577; }","duration":"588.729171ms","start":"2026-04-29T02:44:12.418041Z","end":"2026-04-29T02:44:13.00677Z","steps":["trace[946565139] 'agreement among raft nodes before linearized reading' (duration: 588.443254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.006973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.418023Z","time spent":"588.917715ms","remote":"127.0.0.1:40400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-04-29T02:44:13.006699Z","caller":"traceutil/trace.go:171","msg":"trace[1558054322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24577; }","duration":"406.401941ms","start":"2026-04-29T02:44:12.600289Z","end":"2026-04-29T02:44:13.006691Z","steps":["trace[1558054322] 'agreement among raft nodes before linearized reading' (duration: 406.314369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.007245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:12.600234Z","time spent":"406.994875ms","remote":"127.0.0.1:40488","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-04-29T02:44:13.006687Z","caller":"traceutil/trace.go:171","msg":"trace[1515488291] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24577; }","duration":"214.357151ms","start":"2026-04-29T02:44:12.79231Z","end":"2026-04-29T02:44:13.006668Z","steps":["trace[1515488291] 'agreement among raft nodes before linearized reading' (duration: 214.232178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.008416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.258079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T02:44:13.008507Z","caller":"traceutil/trace.go:171","msg":"trace[516036026] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24577; }","duration":"281.390442ms","start":"2026-04-29T02:44:12.727092Z","end":"2026-04-29T02:44:13.008482Z","steps":["trace[516036026] 'agreement among raft nodes before linearized reading' (duration: 281.175297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.482319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.623447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:13.48245Z","caller":"traceutil/trace.go:171","msg":"trace[856055308] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"469.930429ms","start":"2026-04-29T02:44:13.012499Z","end":"2026-04-29T02:44:13.48243Z","steps":["trace[856055308] 'process raft request' (duration: 286.973574ms)","trace[856055308] 'compare' (duration: 181.47623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:13.482572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.012478Z","time spent":"470.031622ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.694379Z","caller":"traceutil/trace.go:171","msg":"trace[832069621] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"681.589846ms","start":"2026-04-29T02:44:13.012755Z","end":"2026-04-29T02:44:13.694345Z","steps":["trace[832069621] 'process raft request' (duration: 681.008672ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:13.694396Z","caller":"traceutil/trace.go:171","msg":"trace[1669345649] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"575.66573ms","start":"2026-04-29T02:44:13.118711Z","end":"2026-04-29T02:44:13.694377Z","steps":["trace[1669345649] 'process raft request' (duration: 575.588809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.694541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.012751Z","time spent":"681.703218ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:13.694606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.118691Z","time spent":"575.795183ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.694865Z","caller":"traceutil/trace.go:171","msg":"trace[1026738213] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"418.15721ms","start":"2026-04-29T02:44:13.276695Z","end":"2026-04-29T02:44:13.694853Z","steps":["trace[1026738213] 'process raft request' (duration: 417.645918ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:13.694896Z","caller":"traceutil/trace.go:171","msg":"trace[1431297552] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"682.094887ms","start":"2026-04-29T02:44:13.012786Z","end":"2026-04-29T02:44:13.694881Z","steps":["trace[1431297552] 'process raft request' (duration: 681.433432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.694939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.276667Z","time spent":"418.236002ms","remote":"127.0.0.1:40488","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-04-29T02:44:13.694971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.012782Z","time spent":"682.149098ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.694126Z","caller":"traceutil/trace.go:171","msg":"trace[2121218941] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"681.378521ms","start":"2026-04-29T02:44:13.012713Z","end":"2026-04-29T02:44:13.694091Z","steps":["trace[2121218941] 'process raft request' (duration: 664.674724ms)","trace[2121218941] 'compare' (duration: 16.037171ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:13.695133Z","caller":"traceutil/trace.go:171","msg":"trace[991072221] linearizableReadLoop","detail":"{readStateIndex:25386; appliedIndex:25382; }","duration":"668.192168ms","start":"2026-04-29T02:44:13.026929Z","end":"2026-04-29T02:44:13.695121Z","steps":["trace[991072221] 'read index received' (duration: 272.753667ms)","trace[991072221] 'applied index is now lower than readState.Index' (duration: 395.437321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:13.695182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.012705Z","time spent":"682.443055ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.695241Z","caller":"traceutil/trace.go:171","msg":"trace[106271139] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"418.234451ms","start":"2026-04-29T02:44:13.276997Z","end":"2026-04-29T02:44:13.695231Z","steps":["trace[106271139] 'process raft request' (duration: 417.75351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.695306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.382852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T02:44:13.695306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.276968Z","time spent":"418.302343ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.695348Z","caller":"traceutil/trace.go:171","msg":"trace[741159558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24584; }","duration":"668.455934ms","start":"2026-04-29T02:44:13.02688Z","end":"2026-04-29T02:44:13.695336Z","steps":["trace[741159558] 'agreement among raft nodes before linearized reading' (duration: 668.387752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.695388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.026861Z","time spent":"668.518145ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-29T02:44:13.766326Z","caller":"traceutil/trace.go:171","msg":"trace[434571747] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"149.590043ms","start":"2026-04-29T02:44:13.616705Z","end":"2026-04-29T02:44:13.766295Z","steps":["trace[434571747] 'process raft request' (duration: 149.49508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.766369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.203184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:13.766423Z","caller":"traceutil/trace.go:171","msg":"trace[1654906236] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24586; }","duration":"320.317866ms","start":"2026-04-29T02:44:13.446092Z","end":"2026-04-29T02:44:13.76641Z","steps":["trace[1654906236] 'agreement among raft nodes before linearized reading' (duration: 320.165193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.76647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.446073Z","time spent":"320.386798ms","remote":"127.0.0.1:40368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-29T02:44:13.766624Z","caller":"traceutil/trace.go:171","msg":"trace[1653510994] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"381.228283ms","start":"2026-04-29T02:44:13.385382Z","end":"2026-04-29T02:44:13.76661Z","steps":["trace[1653510994] 'process raft request' (duration: 380.65506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.766712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.834628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:13.766819Z","caller":"traceutil/trace.go:171","msg":"trace[616351613] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24586; }","duration":"101.965191ms","start":"2026-04-29T02:44:13.664839Z","end":"2026-04-29T02:44:13.766805Z","steps":["trace[616351613] 'agreement among raft nodes before linearized reading' (duration: 101.827327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:13.766877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.352984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T02:44:13.766752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:13.385359Z","time spent":"381.299895ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:13.76701Z","caller":"traceutil/trace.go:171","msg":"trace[87786407] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24586; }","duration":"135.503148ms","start":"2026-04-29T02:44:13.631468Z","end":"2026-04-29T02:44:13.766971Z","steps":["trace[87786407] 'agreement among raft nodes before linearized reading' (duration: 135.229081ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:15.63088Z","caller":"traceutil/trace.go:171","msg":"trace[95743295] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"138.255613ms","start":"2026-04-29T02:44:15.492582Z","end":"2026-04-29T02:44:15.630838Z","steps":["trace[95743295] 'process raft request' (duration: 137.936565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:20.675259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.112029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:20.675452Z","caller":"traceutil/trace.go:171","msg":"trace[1074129005] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"224.942652ms","start":"2026-04-29T02:44:20.450483Z","end":"2026-04-29T02:44:20.675426Z","steps":["trace[1074129005] 'process raft request' (duration: 78.560296ms)","trace[1074129005] 'compare' (duration: 145.969486ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:20.675647Z","caller":"traceutil/trace.go:171","msg":"trace[2078872284] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"224.692946ms","start":"2026-04-29T02:44:20.450944Z","end":"2026-04-29T02:44:20.675637Z","steps":["trace[2078872284] 'process raft request' (duration: 224.417889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:20.946858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.342376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:20.946987Z","caller":"traceutil/trace.go:171","msg":"trace[767641280] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"174.300688ms","start":"2026-04-29T02:44:20.772656Z","end":"2026-04-29T02:44:20.946957Z","steps":["trace[767641280] 'process raft request' (duration: 43.692696ms)","trace[767641280] 'compare' (duration: 130.08242ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:49.073184Z","caller":"traceutil/trace.go:171","msg":"trace[194776730] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"230.732029ms","start":"2026-04-29T02:44:48.842417Z","end":"2026-04-29T02:44:49.073149Z","steps":["trace[194776730] 'process raft request' (duration: 206.127334ms)","trace[194776730] 'compare' (duration: 24.483842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:49.374473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.115977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:49.374704Z","caller":"traceutil/trace.go:171","msg":"trace[756555604] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"159.724473ms","start":"2026-04-29T02:44:49.21496Z","end":"2026-04-29T02:44:49.374684Z","steps":["trace[756555604] 'process raft request' (duration: 19.237177ms)","trace[756555604] 'compare' (duration: 139.927713ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:49.37518Z","caller":"traceutil/trace.go:171","msg":"trace[1387477524] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"151.286063ms","start":"2026-04-29T02:44:49.223851Z","end":"2026-04-29T02:44:49.375137Z","steps":["trace[1387477524] 'process raft request' (duration: 150.716859ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:50.128966Z","caller":"traceutil/trace.go:171","msg":"trace[1773347153] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"309.007939ms","start":"2026-04-29T02:44:49.819925Z","end":"2026-04-29T02:44:50.128933Z","steps":["trace[1773347153] 'process raft request' (duration: 298.831016ms)","trace[1773347153] 'compare' (duration: 10.05066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:50.129157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.819904Z","time spent":"309.166932ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:50.322017Z","caller":"traceutil/trace.go:171","msg":"trace[1965793561] linearizableReadLoop","detail":"{readStateIndex:25763; appliedIndex:25760; }","duration":"443.959432ms","start":"2026-04-29T02:44:49.878027Z","end":"2026-04-29T02:44:50.321987Z","steps":["trace[1965793561] 'read index received' (duration: 240.722965ms)","trace[1965793561] 'applied index is now lower than readState.Index' (duration: 203.235267ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:50.322104Z","caller":"traceutil/trace.go:171","msg":"trace[462847437] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"501.51786ms","start":"2026-04-29T02:44:49.820536Z","end":"2026-04-29T02:44:50.322054Z","steps":["trace[462847437] 'process raft request' (duration: 410.5706ms)","trace[462847437] 'compare' (duration: 90.645203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:50.32226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.193708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T02:44:50.322304Z","caller":"traceutil/trace.go:171","msg":"trace[429952573] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24949; }","duration":"444.27357ms","start":"2026-04-29T02:44:49.878017Z","end":"2026-04-29T02:44:50.322291Z","steps":["trace[429952573] 'agreement among raft nodes before linearized reading' (duration: 444.138687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.322321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.820516Z","time spent":"501.687984ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T02:44:50.322345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.877942Z","time spent":"444.394133ms","remote":"127.0.0.1:40350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-29T02:44:50.322283Z","caller":"traceutil/trace.go:171","msg":"trace[2051742234] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"501.288035ms","start":"2026-04-29T02:44:49.820942Z","end":"2026-04-29T02:44:50.32223Z","steps":["trace[2051742234] 'process raft request' (duration: 500.989848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.322498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.497398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:50.3226Z","caller":"traceutil/trace.go:171","msg":"trace[61148858] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24949; }","duration":"250.640482ms","start":"2026-04-29T02:44:50.071932Z","end":"2026-04-29T02:44:50.322572Z","steps":["trace[61148858] 'agreement among raft nodes before linearized reading' (duration: 250.463168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.322615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.846691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-04-29T02:44:50.3226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.820922Z","time spent":"501.54342ms","remote":"127.0.0.1:40488","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-04-29T02:44:50.322654Z","caller":"traceutil/trace.go:171","msg":"trace[1182769595] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24949; }","duration":"427.945972ms","start":"2026-04-29T02:44:49.894694Z","end":"2026-04-29T02:44:50.32264Z","steps":["trace[1182769595] 'agreement among raft nodes before linearized reading' (duration: 427.800429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.322794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.348019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-04-29T02:44:50.322629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.422163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-29T02:44:50.322821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.437746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-29T02:44:50.32286Z","caller":"traceutil/trace.go:171","msg":"trace[405425110] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24949; }","duration":"219.453891ms","start":"2026-04-29T02:44:50.103391Z","end":"2026-04-29T02:44:50.322845Z","steps":["trace[405425110] 'agreement among raft nodes before linearized reading' (duration: 219.193035ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:50.322877Z","caller":"traceutil/trace.go:171","msg":"trace[460618085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24949; }","duration":"442.592181ms","start":"2026-04-29T02:44:49.88027Z","end":"2026-04-29T02:44:50.322862Z","steps":["trace[460618085] 'agreement among raft nodes before linearized reading' (duration: 442.391786ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:50.322877Z","caller":"traceutil/trace.go:171","msg":"trace[915974062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24949; }","duration":"444.712ms","start":"2026-04-29T02:44:49.878136Z","end":"2026-04-29T02:44:50.322848Z","steps":["trace[915974062] 'agreement among raft nodes before linearized reading' (duration: 444.168258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.3228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.894681Z","time spent":"428.094487ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-29T02:44:50.322912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.880245Z","time spent":"442.660042ms","remote":"127.0.0.1:40488","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-04-29T02:44:50.322912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:49.878118Z","time spent":"444.787242ms","remote":"127.0.0.1:40488","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-04-29T02:44:50.501808Z","caller":"traceutil/trace.go:171","msg":"trace[1602553351] linearizableReadLoop","detail":"{readStateIndex:25765; appliedIndex:25763; }","duration":"173.301996ms","start":"2026-04-29T02:44:50.328474Z","end":"2026-04-29T02:44:50.501776Z","steps":["trace[1602553351] 'read index received' (duration: 27.288758ms)","trace[1602553351] 'applied index is now lower than readState.Index' (duration: 146.011748ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:50.501845Z","caller":"traceutil/trace.go:171","msg":"trace[1624299296] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"172.518237ms","start":"2026-04-29T02:44:50.329299Z","end":"2026-04-29T02:44:50.501817Z","steps":["trace[1624299296] 'process raft request' (duration: 172.417934ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:50.501892Z","caller":"traceutil/trace.go:171","msg":"trace[1695686036] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"173.820978ms","start":"2026-04-29T02:44:50.32801Z","end":"2026-04-29T02:44:50.501831Z","steps":["trace[1695686036] 'process raft request' (duration: 101.385578ms)","trace[1695686036] 'compare' (duration: 72.173424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:50.50202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.50765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T02:44:50.502093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.99825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:50.502125Z","caller":"traceutil/trace.go:171","msg":"trace[398443634] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24952; }","duration":"173.676264ms","start":"2026-04-29T02:44:50.328418Z","end":"2026-04-29T02:44:50.502094Z","steps":["trace[398443634] 'agreement among raft nodes before linearized reading' (duration: 173.49839ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:50.502133Z","caller":"traceutil/trace.go:171","msg":"trace[51519874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24952; }","duration":"131.100763ms","start":"2026-04-29T02:44:50.371021Z","end":"2026-04-29T02:44:50.502122Z","steps":["trace[51519874] 'agreement among raft nodes before linearized reading' (duration: 131.020791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.502259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.774783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-29T02:44:50.50231Z","caller":"traceutil/trace.go:171","msg":"trace[851510825] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24952; }","duration":"172.847554ms","start":"2026-04-29T02:44:50.329451Z","end":"2026-04-29T02:44:50.502298Z","steps":["trace[851510825] 'agreement among raft nodes before linearized reading' (duration: 172.501246ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:50.799422Z","caller":"traceutil/trace.go:171","msg":"trace[809271392] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"240.778078ms","start":"2026-04-29T02:44:50.558608Z","end":"2026-04-29T02:44:50.799386Z","steps":["trace[809271392] 'process raft request' (duration: 146.691474ms)","trace[809271392] 'compare' (duration: 93.777886ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:50.852566Z","caller":"traceutil/trace.go:171","msg":"trace[665259107] linearizableReadLoop","detail":"{readStateIndex:25768; appliedIndex:25766; }","duration":"223.146909ms","start":"2026-04-29T02:44:50.629376Z","end":"2026-04-29T02:44:50.852523Z","steps":["trace[665259107] 'read index received' (duration: 76.069866ms)","trace[665259107] 'applied index is now lower than readState.Index' (duration: 147.075423ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:50.852613Z","caller":"traceutil/trace.go:171","msg":"trace[997899719] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"280.337807ms","start":"2026-04-29T02:44:50.572236Z","end":"2026-04-29T02:44:50.852574Z","steps":["trace[997899719] 'process raft request' (duration: 280.086761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:50.852734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.339843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:50.852904Z","caller":"traceutil/trace.go:171","msg":"trace[1717355586] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24954; }","duration":"223.577049ms","start":"2026-04-29T02:44:50.629308Z","end":"2026-04-29T02:44:50.852885Z","steps":["trace[1717355586] 'agreement among raft nodes before linearized reading' (duration: 223.350693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:51.094942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.043802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-29T02:44:51.095028Z","caller":"traceutil/trace.go:171","msg":"trace[6380391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24954; }","duration":"171.212706ms","start":"2026-04-29T02:44:50.923799Z","end":"2026-04-29T02:44:51.095012Z","steps":["trace[6380391] 'range keys from in-memory index tree' (duration: 170.892909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:51.578031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.007002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:51.578293Z","caller":"traceutil/trace.go:171","msg":"trace[1914312273] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"197.93998ms","start":"2026-04-29T02:44:51.380335Z","end":"2026-04-29T02:44:51.578275Z","steps":["trace[1914312273] 'process raft request' (duration: 197.831407ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:51.578478Z","caller":"traceutil/trace.go:171","msg":"trace[671739978] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"446.34809ms","start":"2026-04-29T02:44:51.132104Z","end":"2026-04-29T02:44:51.578452Z","steps":["trace[671739978] 'process raft request' (duration: 188.818404ms)","trace[671739978] 'compare' (duration: 256.802728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:51.578605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:51.132079Z","time spent":"446.453693ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:51.799674Z","caller":"traceutil/trace.go:171","msg":"trace[2059516927] linearizableReadLoop","detail":"{readStateIndex:25774; appliedIndex:25773; }","duration":"281.362971ms","start":"2026-04-29T02:44:51.518289Z","end":"2026-04-29T02:44:51.799652Z","steps":["trace[2059516927] 'read index received' (duration: 272.842529ms)","trace[2059516927] 'applied index is now lower than readState.Index' (duration: 8.519312ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:51.79972Z","caller":"traceutil/trace.go:171","msg":"trace[1110557646] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"418.281903ms","start":"2026-04-29T02:44:51.381407Z","end":"2026-04-29T02:44:51.799689Z","steps":["trace[1110557646] 'process raft request' (duration: 409.791471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:51.799779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.477835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:51.799837Z","caller":"traceutil/trace.go:171","msg":"trace[751293253] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24959; }","duration":"281.585708ms","start":"2026-04-29T02:44:51.518236Z","end":"2026-04-29T02:44:51.799822Z","steps":["trace[751293253] 'agreement among raft nodes before linearized reading' (duration: 281.480195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:51.799862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:44:51.381378Z","time spent":"418.402177ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:44:51.811124Z","caller":"traceutil/trace.go:171","msg":"trace[1859871599] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"270.921945ms","start":"2026-04-29T02:44:51.540177Z","end":"2026-04-29T02:44:51.811099Z","steps":["trace[1859871599] 'process raft request' (duration: 270.74601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:51.811282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.560315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-29T02:44:51.811344Z","caller":"traceutil/trace.go:171","msg":"trace[371704284] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24960; }","duration":"230.636887ms","start":"2026-04-29T02:44:51.580674Z","end":"2026-04-29T02:44:51.811311Z","steps":["trace[371704284] 'agreement among raft nodes before linearized reading' (duration: 230.493704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:52.085381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.720273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:52.085482Z","caller":"traceutil/trace.go:171","msg":"trace[1919376554] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"270.027333ms","start":"2026-04-29T02:44:51.815437Z","end":"2026-04-29T02:44:52.085465Z","steps":["trace[1919376554] 'process raft request' (duration: 126.143966ms)","trace[1919376554] 'compare' (duration: 143.541548ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:52.300508Z","caller":"traceutil/trace.go:171","msg":"trace[3204239] linearizableReadLoop","detail":"{readStateIndex:25777; appliedIndex:25776; }","duration":"144.641345ms","start":"2026-04-29T02:44:52.155837Z","end":"2026-04-29T02:44:52.300479Z","steps":["trace[3204239] 'read index received' (duration: 78.865083ms)","trace[3204239] 'applied index is now lower than readState.Index' (duration: 65.775031ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:52.300665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.805238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:44:52.300722Z","caller":"traceutil/trace.go:171","msg":"trace[1382470496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24962; }","duration":"144.926961ms","start":"2026-04-29T02:44:52.15578Z","end":"2026-04-29T02:44:52.300707Z","steps":["trace[1382470496] 'agreement among raft nodes before linearized reading' (duration: 144.811648ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:52.300633Z","caller":"traceutil/trace.go:171","msg":"trace[1501868933] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"165.540001ms","start":"2026-04-29T02:44:52.135058Z","end":"2026-04-29T02:44:52.300598Z","steps":["trace[1501868933] 'process raft request' (duration: 99.746589ms)","trace[1501868933] 'compare' (duration: 65.348892ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:52.443394Z","caller":"traceutil/trace.go:171","msg":"trace[1256078764] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"115.238067ms","start":"2026-04-29T02:44:52.328129Z","end":"2026-04-29T02:44:52.443367Z","steps":["trace[1256078764] 'process raft request' (duration: 86.665418ms)","trace[1256078764] 'compare' (duration: 28.2067ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:52.443448Z","caller":"traceutil/trace.go:171","msg":"trace[1962756942] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"114.473378ms","start":"2026-04-29T02:44:52.328954Z","end":"2026-04-29T02:44:52.443427Z","steps":["trace[1962756942] 'process raft request' (duration: 114.142901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:52.718361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.4483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:52.718699Z","caller":"traceutil/trace.go:171","msg":"trace[1664104146] linearizableReadLoop","detail":"{readStateIndex:25780; appliedIndex:25779; }","duration":"213.398368ms","start":"2026-04-29T02:44:52.505271Z","end":"2026-04-29T02:44:52.718669Z","steps":["trace[1664104146] 'read index received' (duration: 92.505947ms)","trace[1664104146] 'applied index is now lower than readState.Index' (duration: 120.890891ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:52.718782Z","caller":"traceutil/trace.go:171","msg":"trace[1543561142] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"271.281923ms","start":"2026-04-29T02:44:52.447409Z","end":"2026-04-29T02:44:52.718691Z","steps":["trace[1543561142] 'process raft request' (duration: 150.411342ms)","trace[1543561142] 'compare' (duration: 120.00671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:52.718961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.664815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T02:44:52.719066Z","caller":"traceutil/trace.go:171","msg":"trace[246822817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24965; }","duration":"213.812598ms","start":"2026-04-29T02:44:52.505227Z","end":"2026-04-29T02:44:52.719039Z","steps":["trace[246822817] 'agreement among raft nodes before linearized reading' (duration: 213.532261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:52.72428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.188669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-29T02:44:52.724364Z","caller":"traceutil/trace.go:171","msg":"trace[759890090] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24966; }","duration":"217.296751ms","start":"2026-04-29T02:44:52.507044Z","end":"2026-04-29T02:44:52.72434Z","steps":["trace[759890090] 'agreement among raft nodes before linearized reading' (duration: 217.067425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:52.96946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.988255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:52.969646Z","caller":"traceutil/trace.go:171","msg":"trace[188036219] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"241.161096ms","start":"2026-04-29T02:44:52.728466Z","end":"2026-04-29T02:44:52.969628Z","steps":["trace[188036219] 'process raft request' (duration: 89.826263ms)","trace[188036219] 'compare' (duration: 150.705159ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:52.969837Z","caller":"traceutil/trace.go:171","msg":"trace[1441783317] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"165.828738ms","start":"2026-04-29T02:44:52.803987Z","end":"2026-04-29T02:44:52.969816Z","steps":["trace[1441783317] 'process raft request' (duration: 165.576642ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:52.979392Z","caller":"traceutil/trace.go:171","msg":"trace[303849520] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"121.061315ms","start":"2026-04-29T02:44:52.858309Z","end":"2026-04-29T02:44:52.97937Z","steps":["trace[303849520] 'process raft request' (duration: 120.973673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:44:53.417778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.802217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:44:53.417911Z","caller":"traceutil/trace.go:171","msg":"trace[105020533] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"231.081858ms","start":"2026-04-29T02:44:53.186805Z","end":"2026-04-29T02:44:53.417887Z","steps":["trace[105020533] 'process raft request' (duration: 124.067667ms)","trace[105020533] 'compare' (duration: 106.622041ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:54.004586Z","caller":"traceutil/trace.go:171","msg":"trace[138432436] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"163.184476ms","start":"2026-04-29T02:44:53.841374Z","end":"2026-04-29T02:44:54.004559Z","steps":["trace[138432436] 'process raft request' (duration: 163.017122ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:54.550862Z","caller":"traceutil/trace.go:171","msg":"trace[1185619918] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"102.184937ms","start":"2026-04-29T02:44:54.448648Z","end":"2026-04-29T02:44:54.550833Z","steps":["trace[1185619918] 'process raft request' (duration: 102.059394ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:54.550884Z","caller":"traceutil/trace.go:171","msg":"trace[586900231] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"103.002506ms","start":"2026-04-29T02:44:54.447848Z","end":"2026-04-29T02:44:54.550851Z","steps":["trace[586900231] 'process raft request' (duration: 47.418746ms)","trace[586900231] 'compare' (duration: 55.16042ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:54.903007Z","caller":"traceutil/trace.go:171","msg":"trace[1482638259] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"176.860349ms","start":"2026-04-29T02:44:54.726101Z","end":"2026-04-29T02:44:54.902961Z","steps":["trace[1482638259] 'process raft request' (duration: 176.519181ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:55.030706Z","caller":"traceutil/trace.go:171","msg":"trace[942610316] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"122.093569ms","start":"2026-04-29T02:44:54.90856Z","end":"2026-04-29T02:44:55.030653Z","steps":["trace[942610316] 'process raft request' (duration: 52.678571ms)","trace[942610316] 'compare' (duration: 69.269565ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:44:55.105921Z","caller":"traceutil/trace.go:171","msg":"trace[1516254931] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"121.417274ms","start":"2026-04-29T02:44:54.984472Z","end":"2026-04-29T02:44:55.105889Z","steps":["trace[1516254931] 'process raft request' (duration: 121.187939ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:55.940299Z","caller":"traceutil/trace.go:171","msg":"trace[945181654] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"117.513011ms","start":"2026-04-29T02:44:55.822751Z","end":"2026-04-29T02:44:55.940264Z","steps":["trace[945181654] 'process raft request' (duration: 27.937304ms)","trace[945181654] 'compare' (duration: 89.34681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:44:57.142243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.040172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T02:44:57.142326Z","caller":"traceutil/trace.go:171","msg":"trace[666992858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24994; }","duration":"107.203687ms","start":"2026-04-29T02:44:57.035102Z","end":"2026-04-29T02:44:57.142305Z","steps":["trace[666992858] 'range keys from in-memory index tree' (duration: 106.869689ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:44:57.143134Z","caller":"traceutil/trace.go:171","msg":"trace[183046635] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"100.759403ms","start":"2026-04-29T02:44:57.04235Z","end":"2026-04-29T02:44:57.14311Z","steps":["trace[183046635] 'process raft request' (duration: 82.377606ms)","trace[183046635] 'compare' (duration: 17.324763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:45:03.505571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.972489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:45:03.505684Z","caller":"traceutil/trace.go:171","msg":"trace[107615382] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25871; }","duration":"335.364834ms","start":"2026-04-29T02:45:03.1703Z","end":"2026-04-29T02:45:03.505665Z","steps":["trace[107615382] 'read index received' (duration: 44.466426ms)","trace[107615382] 'applied index is now lower than readState.Index' (duration: 290.896798ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:45:03.505753Z","caller":"traceutil/trace.go:171","msg":"trace[195181532] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"340.833004ms","start":"2026-04-29T02:45:03.164883Z","end":"2026-04-29T02:45:03.505716Z","steps":["trace[195181532] 'process raft request' (duration: 129.611309ms)","trace[195181532] 'compare' (duration: 210.688023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-29T02:45:03.505907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.605379ms","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-04-29T02:45:03.505924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:45:03.164866Z","time spent":"340.959577ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T02:45:03.505961Z","caller":"traceutil/trace.go:171","msg":"trace[1511142127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25056; }","duration":"335.677671ms","start":"2026-04-29T02:45:03.170265Z","end":"2026-04-29T02:45:03.505943Z","steps":["trace[1511142127] 'agreement among raft nodes before linearized reading' (duration: 335.499737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:45:03.506016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:45:03.170255Z","time spent":"335.750473ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-29T02:45:03.506139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.957628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T02:45:03.506213Z","caller":"traceutil/trace.go:171","msg":"trace[1734747974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25056; }","duration":"136.05959ms","start":"2026-04-29T02:45:03.370124Z","end":"2026-04-29T02:45:03.506184Z","steps":["trace[1734747974] 'agreement among raft nodes before linearized reading' (duration: 135.942037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:45:03.506203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.64183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-29T02:45:03.506228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.985627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-29T02:45:03.506261Z","caller":"traceutil/trace.go:171","msg":"trace[1398861968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25056; }","duration":"135.895017ms","start":"2026-04-29T02:45:03.370352Z","end":"2026-04-29T02:45:03.506247Z","steps":["trace[1398861968] 'agreement among raft nodes before linearized reading' (duration: 135.707122ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:45:03.506319Z","caller":"traceutil/trace.go:171","msg":"trace[32704195] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25056; }","duration":"333.119821ms","start":"2026-04-29T02:45:03.173178Z","end":"2026-04-29T02:45:03.506298Z","steps":["trace[32704195] 'agreement among raft nodes before linearized reading' (duration: 332.867365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:45:03.506366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T02:45:03.173162Z","time spent":"333.194832ms","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-29T02:45:03.506602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.801929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-29T02:45:03.507752Z","caller":"traceutil/trace.go:171","msg":"trace[1341525504] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25056; }","duration":"162.006507ms","start":"2026-04-29T02:45:03.345728Z","end":"2026-04-29T02:45:03.507735Z","steps":["trace[1341525504] 'agreement among raft nodes before linearized reading' (duration: 160.836119ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:45:03.721897Z","caller":"traceutil/trace.go:171","msg":"trace[1793304457] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"212.117277ms","start":"2026-04-29T02:45:03.509739Z","end":"2026-04-29T02:45:03.721856Z","steps":["trace[1793304457] 'process raft request' (duration: 147.910612ms)","trace[1793304457] 'compare' (duration: 64.061812ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:45:03.807698Z","caller":"traceutil/trace.go:171","msg":"trace[1579751235] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"296.825149ms","start":"2026-04-29T02:45:03.510834Z","end":"2026-04-29T02:45:03.807659Z","steps":["trace[1579751235] 'process raft request' (duration: 296.603674ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:45:03.807788Z","caller":"traceutil/trace.go:171","msg":"trace[1642158373] linearizableReadLoop","detail":"{readStateIndex:25875; appliedIndex:25873; }","duration":"295.476357ms","start":"2026-04-29T02:45:03.512152Z","end":"2026-04-29T02:45:03.807628Z","steps":["trace[1642158373] 'read index received' (duration: 145.422853ms)","trace[1642158373] 'applied index is now lower than readState.Index' (duration: 150.052614ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:45:03.807707Z","caller":"traceutil/trace.go:171","msg":"trace[1362766414] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"294.859953ms","start":"2026-04-29T02:45:03.512821Z","end":"2026-04-29T02:45:03.807681Z","steps":["trace[1362766414] 'process raft request' (duration: 294.76001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:45:03.808006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.836516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-29T02:45:03.808474Z","caller":"traceutil/trace.go:171","msg":"trace[1784873032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25059; }","duration":"296.358418ms","start":"2026-04-29T02:45:03.512101Z","end":"2026-04-29T02:45:03.808459Z","steps":["trace[1784873032] 'agreement among raft nodes before linearized reading' (duration: 295.736423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T02:45:03.988966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.592241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-29T02:45:03.989302Z","caller":"traceutil/trace.go:171","msg":"trace[1963710464] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"176.373149ms","start":"2026-04-29T02:45:03.812892Z","end":"2026-04-29T02:45:03.989265Z","steps":["trace[1963710464] 'process raft request' (duration: 176.251346ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:45:03.989331Z","caller":"traceutil/trace.go:171","msg":"trace[407225945] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"210.373466ms","start":"2026-04-29T02:45:03.778916Z","end":"2026-04-29T02:45:03.98929Z","steps":["trace[407225945] 'process raft request' (duration: 74.301273ms)","trace[407225945] 'compare' (duration: 135.380776ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T02:45:04.134903Z","caller":"traceutil/trace.go:171","msg":"trace[1750086264] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"114.865458ms","start":"2026-04-29T02:45:04.020012Z","end":"2026-04-29T02:45:04.134877Z","steps":["trace[1750086264] 'process raft request' (duration: 114.691934ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:45:04.707558Z","caller":"traceutil/trace.go:171","msg":"trace[359742718] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"123.511083ms","start":"2026-04-29T02:45:04.583997Z","end":"2026-04-29T02:45:04.707508Z","steps":["trace[359742718] 'process raft request' (duration: 123.215516ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:46:30.773649Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a3.wal"} {"level":"info","ts":"2026-04-29T02:46:47.892571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23241} {"level":"info","ts":"2026-04-29T02:46:48.024641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23241,"took":"127.934988ms","hash":3271068911,"current-db-size-bytes":59502592,"current-db-size":"60 MB","current-db-size-in-use-bytes":35794944,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-29T02:46:48.024721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3271068911,"revision":23241,"compact-revision":20029} {"level":"info","ts":"2026-04-29T02:48:48.546098Z","caller":"traceutil/trace.go:171","msg":"trace[1960809219] transaction","detail":"{read_only:false; response_revision:27345; number_of_response:1; }","duration":"103.460286ms","start":"2026-04-29T02:48:48.442608Z","end":"2026-04-29T02:48:48.546069Z","steps":["trace[1960809219] 'process raft request' (duration: 103.256862ms)"],"step_count":1} {"level":"info","ts":"2026-04-29T02:51:47.898364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26099} {"level":"info","ts":"2026-04-29T02:51:48.037959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26099,"took":"135.071867ms","hash":2218124920,"current-db-size-bytes":59502592,"current-db-size":"60 MB","current-db-size-in-use-bytes":33210368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-29T02:51:48.038021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2218124920,"revision":26099,"compact-revision":23241} {"level":"info","ts":"2026-04-29T02:52:37.412914Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T02:52:37.418539Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-29T02:52:37.418663Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-29T02:56:47.90565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28714} {"level":"info","ts":"2026-04-29T02:56:48.045755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28714,"took":"136.046033ms","hash":2162391467,"current-db-size-bytes":59502592,"current-db-size":"60 MB","current-db-size-in-use-bytes":29999104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-29T02:56:48.045819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2162391467,"revision":28714,"compact-revision":26099} {"level":"warn","ts":"2026-04-29T03:00:48.304447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880586997086092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-29T03:00:48.805183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880586997086092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-29T03:00:49.306064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880586997086092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-29T03:00:49.806585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880586997086092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-29T03:00:50.307234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880586997086092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-29T03:00:50.370893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000106661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-29T03:00:50.37098Z","caller":"traceutil/trace.go:171","msg":"trace[40791173] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000270284s","start":"2026-04-29T03:00:48.370693Z","end":"2026-04-29T03:00:50.370964Z","steps":["trace[40791173] 'agreement among raft nodes before linearized reading' (duration: 2.000160652s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.371015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.370676Z","time spent":"2.000332596s","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/29 03:00:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-29T03:00:50.422653Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.048255139s","expected-duration":"1s"} {"level":"info","ts":"2026-04-29T03:00:50.423165Z","caller":"traceutil/trace.go:171","msg":"trace[1363206056] transaction","detail":"{read_only:false; response_revision:32824; number_of_response:1; }","duration":"3.048867923s","start":"2026-04-29T03:00:47.374273Z","end":"2026-04-29T03:00:50.423141Z","steps":["trace[1363206056] 'process raft request' (duration: 3.048683019s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.42332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:47.374247Z","time spent":"3.048995596s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T03:00:50.606599Z","caller":"traceutil/trace.go:171","msg":"trace[1953866489] transaction","detail":"{read_only:false; response_revision:32826; number_of_response:1; }","duration":"2.798734552s","start":"2026-04-29T03:00:47.80784Z","end":"2026-04-29T03:00:50.606575Z","steps":["trace[1953866489] 'process raft request' (duration: 2.79863845s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.606801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:47.807826Z","time spent":"2.798845604s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T03:00:50.606819Z","caller":"traceutil/trace.go:171","msg":"trace[1149930340] transaction","detail":"{read_only:false; response_revision:32825; number_of_response:1; }","duration":"2.799198133s","start":"2026-04-29T03:00:47.807583Z","end":"2026-04-29T03:00:50.606781Z","steps":["trace[1149930340] 'process raft request' (duration: 2.798699831s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.60702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:47.807558Z","time spent":"2.799346936s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-29T03:00:50.608202Z","caller":"traceutil/trace.go:171","msg":"trace[1989241698] linearizableReadLoop","detail":"{readStateIndex:33861; appliedIndex:33855; }","duration":"2.804860348s","start":"2026-04-29T03:00:47.803317Z","end":"2026-04-29T03:00:50.608177Z","steps":["trace[1989241698] 'read index received' (duration: 2.620523878s)","trace[1989241698] 'applied index is now lower than readState.Index' (duration: 184.33546ms)"],"step_count":2} {"level":"info","ts":"2026-04-29T03:00:50.608307Z","caller":"traceutil/trace.go:171","msg":"trace[891431819] transaction","detail":"{read_only:false; response_revision:32828; number_of_response:1; }","duration":"2.291352179s","start":"2026-04-29T03:00:48.316941Z","end":"2026-04-29T03:00:50.608293Z","steps":["trace[891431819] 'process raft request' (duration: 2.291003321s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.608384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.316918Z","time spent":"2.291431842s","remote":"127.0.0.1:40488","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-04-29T03:00:50.608355Z","caller":"traceutil/trace.go:171","msg":"trace[2117213759] transaction","detail":"{read_only:false; response_revision:32827; number_of_response:1; }","duration":"2.631224342s","start":"2026-04-29T03:00:47.977101Z","end":"2026-04-29T03:00:50.608326Z","steps":["trace[2117213759] 'process raft request' (duration: 2.629433609s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.60848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:47.977074Z","time spent":"2.631347725s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T03:00:50.608549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.805237496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-29T03:00:50.608591Z","caller":"traceutil/trace.go:171","msg":"trace[588845074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:32830; }","duration":"2.805310818s","start":"2026-04-29T03:00:47.803268Z","end":"2026-04-29T03:00:50.608579Z","steps":["trace[588845074] 'agreement among raft nodes before linearized reading' (duration: 2.805172825s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.608615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:47.803251Z","time spent":"2.805359579s","remote":"127.0.0.1:40488","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-04-29T03:00:50.608646Z","caller":"traceutil/trace.go:171","msg":"trace[1256454854] transaction","detail":"{read_only:false; response_revision:32829; number_of_response:1; }","duration":"2.289000893s","start":"2026-04-29T03:00:48.319633Z","end":"2026-04-29T03:00:50.608634Z","steps":["trace[1256454854] 'process raft request' (duration: 2.28843918s)"],"step_count":1} {"level":"info","ts":"2026-04-29T03:00:50.608499Z","caller":"traceutil/trace.go:171","msg":"trace[665612191] transaction","detail":"{read_only:false; response_revision:32830; number_of_response:1; }","duration":"1.732568516s","start":"2026-04-29T03:00:48.875863Z","end":"2026-04-29T03:00:50.608431Z","steps":["trace[665612191] 'process raft request' (duration: 1.732263438s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.608717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.319608Z","time spent":"2.289075985s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T03:00:50.608839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.875841Z","time spent":"1.732879182s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-29T03:00:50.633393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.553536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T03:00:50.633486Z","caller":"traceutil/trace.go:171","msg":"trace[122512869] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:32831; }","duration":"720.7295ms","start":"2026-04-29T03:00:49.91274Z","end":"2026-04-29T03:00:50.63347Z","steps":["trace[122512869] 'agreement among raft nodes before linearized reading' (duration: 720.580927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.633498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.24275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T03:00:50.633555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:49.912717Z","time spent":"720.824132ms","remote":"127.0.0.1:40458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-29T03:00:50.633567Z","caller":"traceutil/trace.go:171","msg":"trace[1978923530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32831; }","duration":"262.354782ms","start":"2026-04-29T03:00:50.371195Z","end":"2026-04-29T03:00:50.63355Z","steps":["trace[1978923530] 'agreement among raft nodes before linearized reading' (duration: 262.25593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.633617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.605864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-29T03:00:50.633596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72099149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-29T03:00:50.63366Z","caller":"traceutil/trace.go:171","msg":"trace[770894885] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:32831; }","duration":"988.674055ms","start":"2026-04-29T03:00:49.644971Z","end":"2026-04-29T03:00:50.633645Z","steps":["trace[770894885] 'agreement among raft nodes before linearized reading' (duration: 988.614814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.633697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:49.644954Z","time spent":"988.736677ms","remote":"127.0.0.1:53388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-04-29T03:00:50.633689Z","caller":"traceutil/trace.go:171","msg":"trace[314089060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:32831; }","duration":"1.721122044s","start":"2026-04-29T03:00:48.912541Z","end":"2026-04-29T03:00:50.633663Z","steps":["trace[314089060] 'agreement among raft nodes before linearized reading' (duration: 1.720871958s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.63375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.080936ms","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:6"} {"level":"info","ts":"2026-04-29T03:00:50.633799Z","caller":"traceutil/trace.go:171","msg":"trace[2015714535] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:32831; }","duration":"688.170458ms","start":"2026-04-29T03:00:49.945612Z","end":"2026-04-29T03:00:50.633783Z","steps":["trace[2015714535] 'agreement among raft nodes before linearized reading' (duration: 688.102176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.633832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:49.945586Z","time spent":"688.237649ms","remote":"127.0.0.1:38374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-04-29T03:00:50.633845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039626176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-29T03:00:50.633907Z","caller":"traceutil/trace.go:171","msg":"trace[1347760545] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:32831; }","duration":"1.039731318s","start":"2026-04-29T03:00:49.594161Z","end":"2026-04-29T03:00:50.633892Z","steps":["trace[1347760545] 'agreement among raft nodes before linearized reading' (duration: 1.039645296s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.633538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466015043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-29T03:00:50.633955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:49.594144Z","time spent":"1.039798349s","remote":"127.0.0.1:53520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":27,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-04-29T03:00:50.633749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.912527Z","time spent":"1.721204095s","remote":"127.0.0.1:40488","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-04-29T03:00:50.634016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810386714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-29T03:00:50.634045Z","caller":"traceutil/trace.go:171","msg":"trace[1010748476] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:32831; }","duration":"1.810451296s","start":"2026-04-29T03:00:48.823586Z","end":"2026-04-29T03:00:50.634037Z","steps":["trace[1010748476] 'agreement among raft nodes before linearized reading' (duration: 1.81018961s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.823566Z","time spent":"1.810494307s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-29T03:00:50.633982Z","caller":"traceutil/trace.go:171","msg":"trace[176649826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32831; }","duration":"1.466522045s","start":"2026-04-29T03:00:49.167449Z","end":"2026-04-29T03:00:50.633971Z","steps":["trace[176649826] 'agreement among raft nodes before linearized reading' (duration: 1.465913251s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:49.167426Z","time spent":"1.46670404s","remote":"127.0.0.1:40488","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-04-29T03:00:50.634157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662088301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-29T03:00:50.634194Z","caller":"traceutil/trace.go:171","msg":"trace[375080341] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32831; }","duration":"1.662174554s","start":"2026-04-29T03:00:48.972009Z","end":"2026-04-29T03:00:50.634184Z","steps":["trace[375080341] 'agreement among raft nodes before linearized reading' (duration: 1.661378355s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.971991Z","time spent":"1.662226555s","remote":"127.0.0.1:40488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-29T03:00:50.634032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857202946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-29T03:00:50.634581Z","caller":"traceutil/trace.go:171","msg":"trace[800444766] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:32831; }","duration":"1.857728049s","start":"2026-04-29T03:00:48.776813Z","end":"2026-04-29T03:00:50.634541Z","steps":["trace[800444766] 'agreement among raft nodes before linearized reading' (duration: 1.856446478s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216581539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"warn","ts":"2026-04-29T03:00:50.634639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73570268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-29T03:00:50.634684Z","caller":"traceutil/trace.go:171","msg":"trace[1465329127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32831; }","duration":"1.735787792s","start":"2026-04-29T03:00:48.898885Z","end":"2026-04-29T03:00:50.634673Z","steps":["trace[1465329127] 'agreement among raft nodes before linearized reading' (duration: 1.734544782s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.898861Z","time spent":"1.735844803s","remote":"127.0.0.1:40488","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":"info","ts":"2026-04-29T03:00:50.634706Z","caller":"traceutil/trace.go:171","msg":"trace[653857176] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:32831; }","duration":"1.216911977s","start":"2026-04-29T03:00:49.417761Z","end":"2026-04-29T03:00:50.634673Z","steps":["trace[653857176] 'agreement among raft nodes before linearized reading' (duration: 1.215595146s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739189873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-29T03:00:50.634844Z","caller":"traceutil/trace.go:171","msg":"trace[677574549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32831; }","duration":"1.739645764s","start":"2026-04-29T03:00:48.895178Z","end":"2026-04-29T03:00:50.634824Z","steps":["trace[677574549] 'agreement among raft nodes before linearized reading' (duration: 1.738566389s)"],"step_count":1} {"level":"warn","ts":"2026-04-29T03:00:50.634906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:49.417743Z","time spent":"1.21708449s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2008,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-29T03:00:50.634916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.895165Z","time spent":"1.739731836s","remote":"127.0.0.1:40488","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-04-29T03:00:50.634958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-29T03:00:48.77675Z","time spent":"1.857908723s","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1885,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"info","ts":"2026-04-29T03:01:47.912766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30937} {"level":"info","ts":"2026-04-29T03:01:48.032068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30937,"took":"115.150595ms","hash":1626036648,"current-db-size-bytes":59502592,"current-db-size":"60 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-29T03:01:48.032137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1626036648,"revision":30937,"compact-revision":28714} {"level":"info","ts":"2026-04-29T03:06:47.920189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33250} {"level":"info","ts":"2026-04-29T03:06:48.081292Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33250,"took":"154.909129ms","hash":2754160485,"current-db-size-bytes":61390848,"current-db-size":"61 MB","current-db-size-in-use-bytes":32534528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-29T03:06:48.08137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2754160485,"revision":33250,"compact-revision":30937} {"level":"info","ts":"2026-04-29T03:11:47.928277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35573} {"level":"info","ts":"2026-04-29T03:11:48.080249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35573,"took":"145.401944ms","hash":3251381642,"current-db-size-bytes":62074880,"current-db-size":"62 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-29T03:11:48.080442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3251381642,"revision":35573,"compact-revision":33250} {"level":"info","ts":"2026-04-29T03:13:56.884075Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-29T03:13:56.888021Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-29T03:13:56.888142Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-29T03:16:47.932895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37805} {"level":"info","ts":"2026-04-29T03:16:48.047763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37805,"took":"110.402663ms","hash":42371574,"current-db-size-bytes":62074880,"current-db-size":"62 MB","current-db-size-in-use-bytes":30871552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-29T03:16:48.04782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":42371574,"revision":37805,"compact-revision":35573}