{"level":"warn","ts":"2026-09-19T00:57:43.631578Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:57:43.631753Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-09-19T00:57:43.631876Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:57:43.631896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-19T00:57:43.631924Z","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-09-19T00:57:43.632935Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-09-19T00:57:43.633035Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-09-19T00:57:43.638116Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.739702ms"} {"level":"info","ts":"2026-09-19T00:57:43.645898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-09-19T00:57:43.645975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T00:57:43.646001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-09-19T00:57:43.646022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T00:57:43.646036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-09-19T00:57:43.646079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-09-19T00:57:43.655877Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T00:57:43.658635Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T00:57:43.661666Z","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-09-19T00:57:43.665019Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T00:57:43.665189Z","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-09-19T00:57:43.665328Z","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-09-19T00:57:43.665362Z","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-09-19T00:57:43.665872Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T00:57:43.679587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-09-19T00:57:43.679769Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-19T00:57:43.680994Z","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-09-19T00:57:43.681113Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-19T00:57:43.68114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-19T00:57:43.681532Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T00:57:43.681572Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T00:57:43.947744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T00:57:43.947825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T00:57:43.947851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-09-19T00:57:43.947872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-09-19T00:57:43.947884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-19T00:57:43.947901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-09-19T00:57:43.947921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-19T00:57:43.952083Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T00:57:43.952138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:57:43.952239Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:57:43.95231Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:43.952824Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T00:57:43.952859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T00:57:43.954596Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:43.954729Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:43.954771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:57:43.955866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-09-19T00:57:43.959743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-19T00:57:47.800185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.921344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:57:47.800364Z","caller":"traceutil/trace.go:171","msg":"trace[1256925395] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"347.498702ms","start":"2026-09-19T00:57:47.452845Z","end":"2026-09-19T00:57:47.800344Z","steps":["trace[1256925395] 'read index received' (duration: 36.889813ms)","trace[1256925395] 'applied index is now lower than readState.Index' (duration: 310.606839ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:47.800461Z","caller":"traceutil/trace.go:171","msg":"trace[2005879027] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"385.472656ms","start":"2026-09-19T00:57:47.414943Z","end":"2026-09-19T00:57:47.800415Z","steps":["trace[2005879027] 'process raft request' (duration: 262.652386ms)","trace[2005879027] 'compare' (duration: 121.664007ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:47.800513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.639226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:57:47.80061Z","caller":"traceutil/trace.go:171","msg":"trace[9872659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:204; }","duration":"347.828603ms","start":"2026-09-19T00:57:47.452753Z","end":"2026-09-19T00:57:47.800582Z","steps":["trace[9872659] 'agreement among raft nodes before linearized reading' (duration: 347.654157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:47.800656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:47.452722Z","time spent":"347.924615ms","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T00:57:47.800675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:47.414929Z","time spent":"385.634082ms","remote":"127.0.0.1:57766","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-09-19T00:57:48.10199Z","caller":"traceutil/trace.go:171","msg":"trace[1957206910] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"294.473343ms","start":"2026-09-19T00:57:47.807473Z","end":"2026-09-19T00:57:48.101946Z","steps":["trace[1957206910] 'process raft request' (duration: 207.523362ms)","trace[1957206910] 'compare' (duration: 86.493247ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:31.740002Z","caller":"traceutil/trace.go:171","msg":"trace[2101518277] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"230.454721ms","start":"2026-09-19T00:58:31.50951Z","end":"2026-09-19T00:58:31.739965Z","steps":["trace[2101518277] 'process raft request' (duration: 229.772685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.324246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026151930094435,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T00:58:32.568186Z","caller":"traceutil/trace.go:171","msg":"trace[1671304009] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"748.309738ms","start":"2026-09-19T00:58:31.819831Z","end":"2026-09-19T00:58:32.568141Z","steps":["trace[1671304009] 'process raft request' (duration: 702.773883ms)","trace[1671304009] 'compare' (duration: 45.222998ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:32.568374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.819803Z","time spent":"748.484751ms","remote":"127.0.0.1:57986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T00:58:32.575842Z","caller":"traceutil/trace.go:171","msg":"trace[1876526387] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"751.605183ms","start":"2026-09-19T00:58:31.824176Z","end":"2026-09-19T00:58:32.575781Z","steps":["trace[1876526387] 'read index received' (duration: 698.522767ms)","trace[1876526387] 'applied index is now lower than readState.Index' (duration: 53.081006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:32.576077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.863199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T00:58:32.576224Z","caller":"traceutil/trace.go:171","msg":"trace[1022972417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"752.067403ms","start":"2026-09-19T00:58:31.824143Z","end":"2026-09-19T00:58:32.57621Z","steps":["trace[1022972417] 'agreement among raft nodes before linearized reading' (duration: 751.757536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.576257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.824134Z","time spent":"752.114224ms","remote":"127.0.0.1:57766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T00:58:32.576629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.06477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-19T00:58:32.576684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.002411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-19T00:58:32.576878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.285914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vdjld\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T00:58:32.576907Z","caller":"traceutil/trace.go:171","msg":"trace[111155634] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vdjld; range_end:; response_count:1; response_revision:644; }","duration":"691.369167ms","start":"2026-09-19T00:58:31.885529Z","end":"2026-09-19T00:58:32.576898Z","steps":["trace[111155634] 'agreement among raft nodes before linearized reading' (duration: 691.311366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.576928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.885501Z","time spent":"691.421427ms","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vdjld\" "} {"level":"warn","ts":"2026-09-19T00:58:32.576811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.775985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-19T00:58:32.577131Z","caller":"traceutil/trace.go:171","msg":"trace[1534302042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"366.01668ms","start":"2026-09-19T00:58:32.211017Z","end":"2026-09-19T00:58:32.577034Z","steps":["trace[1534302042] 'agreement among raft nodes before linearized reading' (duration: 365.722963ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:32.576778Z","caller":"traceutil/trace.go:171","msg":"trace[1374059021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"124.272265ms","start":"2026-09-19T00:58:32.452478Z","end":"2026-09-19T00:58:32.57675Z","steps":["trace[1374059021] 'agreement among raft nodes before linearized reading' (duration: 123.922697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.577206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:32.210927Z","time spent":"366.258166ms","remote":"127.0.0.1:57766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T00:58:32.577084Z","caller":"traceutil/trace.go:171","msg":"trace[1153605072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"720.370889ms","start":"2026-09-19T00:58:31.85659Z","end":"2026-09-19T00:58:32.576961Z","steps":["trace[1153605072] 'agreement among raft nodes before linearized reading' (duration: 719.798167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:32.577536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:58:31.856562Z","time spent":"720.958333ms","remote":"127.0.0.1:57766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T00:58:48.181459Z","caller":"traceutil/trace.go:171","msg":"trace[265266504] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"169.287987ms","start":"2026-09-19T00:58:48.012144Z","end":"2026-09-19T00:58:48.181432Z","steps":["trace[265266504] 'read index received' (duration: 169.007981ms)","trace[265266504] 'applied index is now lower than readState.Index' (duration: 279.005µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:58:48.181594Z","caller":"traceutil/trace.go:171","msg":"trace[1115918055] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"180.178408ms","start":"2026-09-19T00:58:48.001399Z","end":"2026-09-19T00:58:48.181577Z","steps":["trace[1115918055] 'process raft request' (duration: 179.836301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:58:48.181848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.668145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-19T00:58:48.181957Z","caller":"traceutil/trace.go:171","msg":"trace[556682100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"169.829739ms","start":"2026-09-19T00:58:48.012107Z","end":"2026-09-19T00:58:48.181937Z","steps":["trace[556682100] 'agreement among raft nodes before linearized reading' (duration: 169.521373ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:20.412431Z","caller":"traceutil/trace.go:171","msg":"trace[1785299949] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"181.930612ms","start":"2026-09-19T00:59:20.230466Z","end":"2026-09-19T00:59:20.412396Z","steps":["trace[1785299949] 'process raft request' (duration: 181.725128ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:13.504781Z","caller":"traceutil/trace.go:171","msg":"trace[1365946047] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"201.405842ms","start":"2026-09-19T01:00:13.303351Z","end":"2026-09-19T01:00:13.504757Z","steps":["trace[1365946047] 'process raft request' (duration: 201.194038ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:13.504715Z","caller":"traceutil/trace.go:171","msg":"trace[986067154] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1982; }","duration":"182.468265ms","start":"2026-09-19T01:00:13.322221Z","end":"2026-09-19T01:00:13.504689Z","steps":["trace[986067154] 'read index received' (duration: 182.314812ms)","trace[986067154] 'applied index is now lower than readState.Index' (duration: 152.113µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:00:13.50498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.73683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-19T01:00:13.505019Z","caller":"traceutil/trace.go:171","msg":"trace[9611999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1901; }","duration":"182.826521ms","start":"2026-09-19T01:00:13.322181Z","end":"2026-09-19T01:00:13.505007Z","steps":["trace[9611999] 'agreement among raft nodes before linearized reading' (duration: 182.639168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:13.505157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.968027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T01:00:13.505189Z","caller":"traceutil/trace.go:171","msg":"trace[1217541845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1901; }","duration":"149.051368ms","start":"2026-09-19T01:00:13.356131Z","end":"2026-09-19T01:00:13.505182Z","steps":["trace[1217541845] 'agreement among raft nodes before linearized reading' (duration: 148.967407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:13.505283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.633791ms","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-09-19T01:00:13.505394Z","caller":"traceutil/trace.go:171","msg":"trace[448100838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1901; }","duration":"157.784893ms","start":"2026-09-19T01:00:13.347576Z","end":"2026-09-19T01:00:13.505361Z","steps":["trace[448100838] 'agreement among raft nodes before linearized reading' (duration: 157.468198ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:13.635809Z","caller":"traceutil/trace.go:171","msg":"trace[1613440151] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"125.547528ms","start":"2026-09-19T01:00:13.510222Z","end":"2026-09-19T01:00:13.63577Z","steps":["trace[1613440151] 'process raft request' (duration: 107.000778ms)","trace[1613440151] 'compare' (duration: 18.318626ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:13.690288Z","caller":"traceutil/trace.go:171","msg":"trace[655573780] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"179.99451ms","start":"2026-09-19T01:00:13.510238Z","end":"2026-09-19T01:00:13.690232Z","steps":["trace[655573780] 'process raft request' (duration: 179.572682ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:13.690705Z","caller":"traceutil/trace.go:171","msg":"trace[610190085] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"180.281366ms","start":"2026-09-19T01:00:13.510384Z","end":"2026-09-19T01:00:13.690665Z","steps":["trace[610190085] 'process raft request' (duration: 179.625164ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:13.691263Z","caller":"traceutil/trace.go:171","msg":"trace[1313335009] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1983; }","duration":"149.71743ms","start":"2026-09-19T01:00:13.541526Z","end":"2026-09-19T01:00:13.691243Z","steps":["trace[1313335009] 'read index received' (duration: 75.670449ms)","trace[1313335009] 'applied index is now lower than readState.Index' (duration: 74.045321ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:00:13.691475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.879023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:00:13.691536Z","caller":"traceutil/trace.go:171","msg":"trace[50147446] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1905; }","duration":"150.012566ms","start":"2026-09-19T01:00:13.541506Z","end":"2026-09-19T01:00:13.691518Z","steps":["trace[50147446] 'agreement among raft nodes before linearized reading' (duration: 149.807002ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:13.691636Z","caller":"traceutil/trace.go:171","msg":"trace[274792459] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"177.74126ms","start":"2026-09-19T01:00:13.513751Z","end":"2026-09-19T01:00:13.691492Z","steps":["trace[274792459] 'process raft request' (duration: 176.693021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:13.943488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.61228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-19T01:00:13.943599Z","caller":"traceutil/trace.go:171","msg":"trace[413481986] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1906; }","duration":"116.763102ms","start":"2026-09-19T01:00:13.826811Z","end":"2026-09-19T01:00:13.943574Z","steps":["trace[413481986] 'range keys from in-memory index tree' (duration: 116.433237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:13.943278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.590031ms","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-09-19T01:00:13.944473Z","caller":"traceutil/trace.go:171","msg":"trace[1297687138] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1906; }","duration":"186.843793ms","start":"2026-09-19T01:00:13.7576Z","end":"2026-09-19T01:00:13.944443Z","steps":["trace[1297687138] 'range keys from in-memory index tree' (duration: 185.305076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:00:48.067552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.859862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-19T01:00:48.06771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.941888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:00:48.067736Z","caller":"traceutil/trace.go:171","msg":"trace[853474740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2144; }","duration":"244.097836ms","start":"2026-09-19T01:00:47.823611Z","end":"2026-09-19T01:00:48.067709Z","steps":["trace[853474740] 'range keys from in-memory index tree' (duration: 243.626718ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:48.068024Z","caller":"traceutil/trace.go:171","msg":"trace[666657531] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"186.209364ms","start":"2026-09-19T01:00:47.881638Z","end":"2026-09-19T01:00:48.067847Z","steps":["trace[666657531] 'process raft request' (duration: 36.994481ms)","trace[666657531] 'compare' (duration: 148.612623ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:48.230194Z","caller":"traceutil/trace.go:171","msg":"trace[904105262] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"157.19934ms","start":"2026-09-19T01:00:48.072961Z","end":"2026-09-19T01:00:48.230161Z","steps":["trace[904105262] 'process raft request' (duration: 112.384824ms)","trace[904105262] 'compare' (duration: 44.624763ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:00:48.231132Z","caller":"traceutil/trace.go:171","msg":"trace[496219280] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"156.967416ms","start":"2026-09-19T01:00:48.074138Z","end":"2026-09-19T01:00:48.231106Z","steps":["trace[496219280] 'process raft request' (duration: 155.986619ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:00:48.231943Z","caller":"traceutil/trace.go:171","msg":"trace[480027988] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"156.00573ms","start":"2026-09-19T01:00:48.07592Z","end":"2026-09-19T01:00:48.231926Z","steps":["trace[480027988] 'process raft request' (duration: 155.225466ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:02:00.500784Z","caller":"traceutil/trace.go:171","msg":"trace[705314333] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"112.291036ms","start":"2026-09-19T01:02:00.388458Z","end":"2026-09-19T01:02:00.500749Z","steps":["trace[705314333] 'process raft request' (duration: 112.095651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:35.141392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.861168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:02:35.141647Z","caller":"traceutil/trace.go:171","msg":"trace[1206353919] linearizableReadLoop","detail":"{readStateIndex:2942; appliedIndex:2940; }","duration":"239.54441ms","start":"2026-09-19T01:02:34.902082Z","end":"2026-09-19T01:02:35.141627Z","steps":["trace[1206353919] 'read index received' (duration: 81.489768ms)","trace[1206353919] 'applied index is now lower than readState.Index' (duration: 158.053582ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:35.141891Z","caller":"traceutil/trace.go:171","msg":"trace[100167172] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"240.890553ms","start":"2026-09-19T01:02:34.900986Z","end":"2026-09-19T01:02:35.141876Z","steps":["trace[100167172] 'process raft request' (duration: 82.430341ms)","trace[100167172] 'compare' (duration: 157.689174ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:02:35.142086Z","caller":"traceutil/trace.go:171","msg":"trace[967021755] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"240.207217ms","start":"2026-09-19T01:02:34.901869Z","end":"2026-09-19T01:02:35.142076Z","steps":["trace[967021755] 'process raft request' (duration: 239.693224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:35.142296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.204977ms","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-09-19T01:02:35.142326Z","caller":"traceutil/trace.go:171","msg":"trace[812828821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2828; }","duration":"240.271568ms","start":"2026-09-19T01:02:34.902042Z","end":"2026-09-19T01:02:35.142313Z","steps":["trace[812828821] 'agreement among raft nodes before linearized reading' (duration: 240.144635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:02:35.142514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.239868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T01:02:35.14254Z","caller":"traceutil/trace.go:171","msg":"trace[1041132622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2828; }","duration":"240.290048ms","start":"2026-09-19T01:02:34.902242Z","end":"2026-09-19T01:02:35.142532Z","steps":["trace[1041132622] 'agreement among raft nodes before linearized reading' (duration: 240.189696ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.309169Z","caller":"traceutil/trace.go:171","msg":"trace[2097717243] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"186.752255ms","start":"2026-09-19T01:07:14.122398Z","end":"2026-09-19T01:07:14.30915Z","steps":["trace[2097717243] 'process raft request' (duration: 186.669494ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.309157Z","caller":"traceutil/trace.go:171","msg":"trace[2030570725] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"186.853447ms","start":"2026-09-19T01:07:14.122276Z","end":"2026-09-19T01:07:14.30913Z","steps":["trace[2030570725] 'process raft request' (duration: 186.708695ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.309221Z","caller":"traceutil/trace.go:171","msg":"trace[1172351851] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"191.31567ms","start":"2026-09-19T01:07:14.117895Z","end":"2026-09-19T01:07:14.309211Z","steps":["trace[1172351851] 'process raft request' (duration: 159.436854ms)","trace[1172351851] 'compare' (duration: 31.534529ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:14.309751Z","caller":"traceutil/trace.go:171","msg":"trace[1465581495] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"187.019691ms","start":"2026-09-19T01:07:14.122718Z","end":"2026-09-19T01:07:14.309738Z","steps":["trace[1465581495] 'process raft request' (duration: 186.395989ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.309884Z","caller":"traceutil/trace.go:171","msg":"trace[888079375] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"184.887851ms","start":"2026-09-19T01:07:14.124988Z","end":"2026-09-19T01:07:14.309875Z","steps":["trace[888079375] 'process raft request' (duration: 184.687568ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.310075Z","caller":"traceutil/trace.go:171","msg":"trace[10974291] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"180.899259ms","start":"2026-09-19T01:07:14.129164Z","end":"2026-09-19T01:07:14.310063Z","steps":["trace[10974291] 'process raft request' (duration: 180.596973ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.310245Z","caller":"traceutil/trace.go:171","msg":"trace[1073440026] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4648; }","duration":"179.027695ms","start":"2026-09-19T01:07:14.131203Z","end":"2026-09-19T01:07:14.310231Z","steps":["trace[1073440026] 'process raft request' (duration: 178.78651ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.310625Z","caller":"traceutil/trace.go:171","msg":"trace[1452217960] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"100.5903ms","start":"2026-09-19T01:07:14.210019Z","end":"2026-09-19T01:07:14.31061Z","steps":["trace[1452217960] 'process raft request' (duration: 100.366476ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.310684Z","caller":"traceutil/trace.go:171","msg":"trace[1945553996] linearizableReadLoop","detail":"{readStateIndex:4833; appliedIndex:4820; }","duration":"192.229467ms","start":"2026-09-19T01:07:14.118443Z","end":"2026-09-19T01:07:14.310672Z","steps":["trace[1945553996] 'read index received' (duration: 63.007219ms)","trace[1945553996] 'applied index is now lower than readState.Index' (duration: 129.220478ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:14.311377Z","caller":"traceutil/trace.go:171","msg":"trace[2056993055] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"111.637734ms","start":"2026-09-19T01:07:14.199726Z","end":"2026-09-19T01:07:14.311364Z","steps":["trace[2056993055] 'process raft request' (duration: 110.532784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:14.311809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.147458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T01:07:14.31184Z","caller":"traceutil/trace.go:171","msg":"trace[338987546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4658; }","duration":"165.20854ms","start":"2026-09-19T01:07:14.14662Z","end":"2026-09-19T01:07:14.311829Z","steps":["trace[338987546] 'agreement among raft nodes before linearized reading' (duration: 165.159029ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.311403Z","caller":"traceutil/trace.go:171","msg":"trace[1291063217] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"119.236793ms","start":"2026-09-19T01:07:14.192158Z","end":"2026-09-19T01:07:14.311395Z","steps":["trace[1291063217] 'process raft request' (duration: 118.038821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:14.312086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.39573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:10878"} {"level":"info","ts":"2026-09-19T01:07:14.312108Z","caller":"traceutil/trace.go:171","msg":"trace[1212693699] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4658; }","duration":"111.454371ms","start":"2026-09-19T01:07:14.200647Z","end":"2026-09-19T01:07:14.312101Z","steps":["trace[1212693699] 'agreement among raft nodes before linearized reading' (duration: 111.387878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:14.312238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.4454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:5066"} {"level":"info","ts":"2026-09-19T01:07:14.312263Z","caller":"traceutil/trace.go:171","msg":"trace[288569375] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4658; }","duration":"112.48783ms","start":"2026-09-19T01:07:14.199768Z","end":"2026-09-19T01:07:14.312256Z","steps":["trace[288569375] 'agreement among raft nodes before linearized reading' (duration: 112.423489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:14.31238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.64529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:1176"} {"level":"info","ts":"2026-09-19T01:07:14.312405Z","caller":"traceutil/trace.go:171","msg":"trace[1811049791] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4658; }","duration":"113.711472ms","start":"2026-09-19T01:07:14.198686Z","end":"2026-09-19T01:07:14.312397Z","steps":["trace[1811049791] 'agreement among raft nodes before linearized reading' (duration: 113.649261ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.311416Z","caller":"traceutil/trace.go:171","msg":"trace[1695543848] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"101.597229ms","start":"2026-09-19T01:07:14.209811Z","end":"2026-09-19T01:07:14.311409Z","steps":["trace[1695543848] 'process raft request' (duration: 100.55036ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.311456Z","caller":"traceutil/trace.go:171","msg":"trace[1800295307] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"107.290504ms","start":"2026-09-19T01:07:14.20414Z","end":"2026-09-19T01:07:14.311431Z","steps":["trace[1800295307] 'process raft request' (duration: 106.191133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:14.311564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.105393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7756fb7857-4hbhm\" ","response":"range_response_count:1 size:10026"} {"level":"info","ts":"2026-09-19T01:07:14.312732Z","caller":"traceutil/trace.go:171","msg":"trace[1425517798] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7756fb7857-4hbhm; range_end:; response_count:1; response_revision:4658; }","duration":"194.293536ms","start":"2026-09-19T01:07:14.118425Z","end":"2026-09-19T01:07:14.312719Z","steps":["trace[1425517798] 'agreement among raft nodes before linearized reading' (duration: 193.051152ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530247Z","caller":"traceutil/trace.go:171","msg":"trace[2131558057] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"208.795732ms","start":"2026-09-19T01:07:14.321421Z","end":"2026-09-19T01:07:14.530217Z","steps":["trace[2131558057] 'process raft request' (duration: 201.371746ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530412Z","caller":"traceutil/trace.go:171","msg":"trace[1576931614] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"208.69826ms","start":"2026-09-19T01:07:14.32167Z","end":"2026-09-19T01:07:14.530369Z","steps":["trace[1576931614] 'process raft request' (duration: 208.507446ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530515Z","caller":"traceutil/trace.go:171","msg":"trace[1704332736] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"202.944894ms","start":"2026-09-19T01:07:14.327563Z","end":"2026-09-19T01:07:14.530508Z","steps":["trace[1704332736] 'process raft request' (duration: 202.7768ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.53051Z","caller":"traceutil/trace.go:171","msg":"trace[495369721] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"208.864254ms","start":"2026-09-19T01:07:14.321625Z","end":"2026-09-19T01:07:14.53049Z","steps":["trace[495369721] 'process raft request' (duration: 208.448015ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530583Z","caller":"traceutil/trace.go:171","msg":"trace[2017642062] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"202.664449ms","start":"2026-09-19T01:07:14.327911Z","end":"2026-09-19T01:07:14.530575Z","steps":["trace[2017642062] 'process raft request' (duration: 202.519476ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530644Z","caller":"traceutil/trace.go:171","msg":"trace[1868432201] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"202.711079ms","start":"2026-09-19T01:07:14.32792Z","end":"2026-09-19T01:07:14.530631Z","steps":["trace[1868432201] 'process raft request' (duration: 202.456444ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530952Z","caller":"traceutil/trace.go:171","msg":"trace[1124325517] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"202.841962ms","start":"2026-09-19T01:07:14.328099Z","end":"2026-09-19T01:07:14.530941Z","steps":["trace[1124325517] 'process raft request' (duration: 202.466965ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.531024Z","caller":"traceutil/trace.go:171","msg":"trace[1502657059] linearizableReadLoop","detail":"{readStateIndex:4868; appliedIndex:4853; }","duration":"194.421917ms","start":"2026-09-19T01:07:14.336596Z","end":"2026-09-19T01:07:14.531018Z","steps":["trace[1502657059] 'read index received' (duration: 7.018699ms)","trace[1502657059] 'applied index is now lower than readState.Index' (duration: 187.402628ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:14.530683Z","caller":"traceutil/trace.go:171","msg":"trace[870598388] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"202.605648ms","start":"2026-09-19T01:07:14.32804Z","end":"2026-09-19T01:07:14.530645Z","steps":["trace[870598388] 'process raft request' (duration: 202.469925ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530748Z","caller":"traceutil/trace.go:171","msg":"trace[144495061] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"202.74954ms","start":"2026-09-19T01:07:14.32799Z","end":"2026-09-19T01:07:14.530739Z","steps":["trace[144495061] 'process raft request' (duration: 202.492006ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530848Z","caller":"traceutil/trace.go:171","msg":"trace[459306284] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"189.456736ms","start":"2026-09-19T01:07:14.341383Z","end":"2026-09-19T01:07:14.53084Z","steps":["trace[459306284] 'process raft request' (duration: 189.359944ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.53087Z","caller":"traceutil/trace.go:171","msg":"trace[485809950] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"196.032558ms","start":"2026-09-19T01:07:14.334832Z","end":"2026-09-19T01:07:14.530865Z","steps":["trace[485809950] 'process raft request' (duration: 195.838544ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530889Z","caller":"traceutil/trace.go:171","msg":"trace[973162294] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"195.474447ms","start":"2026-09-19T01:07:14.335409Z","end":"2026-09-19T01:07:14.530884Z","steps":["trace[973162294] 'process raft request' (duration: 195.301414ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530905Z","caller":"traceutil/trace.go:171","msg":"trace[1076419697] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"202.794051ms","start":"2026-09-19T01:07:14.328095Z","end":"2026-09-19T01:07:14.530889Z","steps":["trace[1076419697] 'process raft request' (duration: 202.499896ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.53138Z","caller":"traceutil/trace.go:171","msg":"trace[1590619684] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"201.538608ms","start":"2026-09-19T01:07:14.329822Z","end":"2026-09-19T01:07:14.53136Z","steps":["trace[1590619684] 'process raft request' (duration: 200.807935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:14.531506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.896886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-19T01:07:14.531536Z","caller":"traceutil/trace.go:171","msg":"trace[720605779] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4677; }","duration":"194.961587ms","start":"2026-09-19T01:07:14.336568Z","end":"2026-09-19T01:07:14.531529Z","steps":["trace[720605779] 'agreement among raft nodes before linearized reading' (duration: 194.652202ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530941Z","caller":"traceutil/trace.go:171","msg":"trace[984375733] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"202.867953ms","start":"2026-09-19T01:07:14.328068Z","end":"2026-09-19T01:07:14.530935Z","steps":["trace[984375733] 'process raft request' (duration: 202.471066ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:14.530964Z","caller":"traceutil/trace.go:171","msg":"trace[1389312958] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"209.122928ms","start":"2026-09-19T01:07:14.321835Z","end":"2026-09-19T01:07:14.530958Z","steps":["trace[1389312958] 'process raft request' (duration: 208.383524ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:26.845025Z","caller":"traceutil/trace.go:171","msg":"trace[158922980] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"173.98222ms","start":"2026-09-19T01:07:26.671015Z","end":"2026-09-19T01:07:26.844997Z","steps":["trace[158922980] 'process raft request' (duration: 113.396509ms)","trace[158922980] 'compare' (duration: 60.131512ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:26.845065Z","caller":"traceutil/trace.go:171","msg":"trace[746819464] linearizableReadLoop","detail":"{readStateIndex:5227; appliedIndex:5225; }","duration":"127.938527ms","start":"2026-09-19T01:07:26.717074Z","end":"2026-09-19T01:07:26.845013Z","steps":["trace[746819464] 'read index received' (duration: 67.350075ms)","trace[746819464] 'applied index is now lower than readState.Index' (duration: 60.587482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:26.84536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.269983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ef921a0-65eb-42e6-90ae-2506838cfa10\" ","response":"range_response_count:1 size:4068"} {"level":"info","ts":"2026-09-19T01:07:26.84568Z","caller":"traceutil/trace.go:171","msg":"trace[1612891723] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"173.278257ms","start":"2026-09-19T01:07:26.67239Z","end":"2026-09-19T01:07:26.845668Z","steps":["trace[1612891723] 'process raft request' (duration: 172.415012ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:26.845429Z","caller":"traceutil/trace.go:171","msg":"trace[594443153] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ef921a0-65eb-42e6-90ae-2506838cfa10; range_end:; response_count:1; response_revision:5027; }","duration":"128.372504ms","start":"2026-09-19T01:07:26.717041Z","end":"2026-09-19T01:07:26.845413Z","steps":["trace[594443153] 'agreement among raft nodes before linearized reading' (duration: 128.187071ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:31.002787Z","caller":"traceutil/trace.go:171","msg":"trace[1771046252] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"105.430781ms","start":"2026-09-19T01:07:30.897339Z","end":"2026-09-19T01:07:31.00277Z","steps":["trace[1771046252] 'process raft request' (duration: 105.40492ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:31.002853Z","caller":"traceutil/trace.go:171","msg":"trace[1071107960] linearizableReadLoop","detail":"{readStateIndex:5303; appliedIndex:5302; }","duration":"105.543313ms","start":"2026-09-19T01:07:30.897297Z","end":"2026-09-19T01:07:31.00284Z","steps":["trace[1071107960] 'read index received' (duration: 54.175083ms)","trace[1071107960] 'applied index is now lower than readState.Index' (duration: 51.35253ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:31.00292Z","caller":"traceutil/trace.go:171","msg":"trace[1695869617] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"106.229275ms","start":"2026-09-19T01:07:30.896672Z","end":"2026-09-19T01:07:31.002901Z","steps":["trace[1695869617] 'process raft request' (duration: 54.787723ms)","trace[1695869617] 'compare' (duration: 51.204658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:31.003001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.690825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-19T01:07:31.003034Z","caller":"traceutil/trace.go:171","msg":"trace[1272485760] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5101; }","duration":"105.754236ms","start":"2026-09-19T01:07:30.897271Z","end":"2026-09-19T01:07:31.003026Z","steps":["trace[1272485760] 'agreement among raft nodes before linearized reading' (duration: 105.643954ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:32.52499Z","caller":"traceutil/trace.go:171","msg":"trace[1039636270] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"240.974491ms","start":"2026-09-19T01:07:32.283988Z","end":"2026-09-19T01:07:32.524962Z","steps":["trace[1039636270] 'process raft request' (duration: 161.791192ms)","trace[1039636270] 'compare' (duration: 78.764502ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:32.939741Z","caller":"traceutil/trace.go:171","msg":"trace[898287393] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"131.917115ms","start":"2026-09-19T01:07:32.807783Z","end":"2026-09-19T01:07:32.9397Z","steps":["trace[898287393] 'process raft request' (duration: 131.615669ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:33.005089Z","caller":"traceutil/trace.go:171","msg":"trace[1140342045] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"192.618855ms","start":"2026-09-19T01:07:32.812427Z","end":"2026-09-19T01:07:33.005045Z","steps":["trace[1140342045] 'process raft request' (duration: 187.895369ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:33.005307Z","caller":"traceutil/trace.go:171","msg":"trace[200380767] linearizableReadLoop","detail":"{readStateIndex:5347; appliedIndex:5345; }","duration":"166.043249ms","start":"2026-09-19T01:07:32.839241Z","end":"2026-09-19T01:07:33.005284Z","steps":["trace[200380767] 'read index received' (duration: 100.155693ms)","trace[200380767] 'applied index is now lower than readState.Index' (duration: 65.886656ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:33.005417Z","caller":"traceutil/trace.go:171","msg":"trace[1851419008] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"120.545927ms","start":"2026-09-19T01:07:32.884858Z","end":"2026-09-19T01:07:33.005404Z","steps":["trace[1851419008] 'process raft request' (duration: 120.357422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:33.005606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.355386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-19T01:07:33.005639Z","caller":"traceutil/trace.go:171","msg":"trace[1823141014] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5145; }","duration":"166.433787ms","start":"2026-09-19T01:07:32.839195Z","end":"2026-09-19T01:07:33.005629Z","steps":["trace[1823141014] 'agreement among raft nodes before linearized reading' (duration: 166.317764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:33.005703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.473552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:07:33.005801Z","caller":"traceutil/trace.go:171","msg":"trace[465526731] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5145; }","duration":"140.600954ms","start":"2026-09-19T01:07:32.865175Z","end":"2026-09-19T01:07:33.005776Z","steps":["trace[465526731] 'agreement among raft nodes before linearized reading' (duration: 140.421831ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:33.005328Z","caller":"traceutil/trace.go:171","msg":"trace[2084741047] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"120.458735ms","start":"2026-09-19T01:07:32.884841Z","end":"2026-09-19T01:07:33.0053Z","steps":["trace[2084741047] 'process raft request' (duration: 120.127848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:33.353914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.98395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:07:33.354084Z","caller":"traceutil/trace.go:171","msg":"trace[1083176889] linearizableReadLoop","detail":"{readStateIndex:5351; appliedIndex:5350; }","duration":"264.780085ms","start":"2026-09-19T01:07:33.089274Z","end":"2026-09-19T01:07:33.354054Z","steps":["trace[1083176889] 'read index received' (duration: 88.417068ms)","trace[1083176889] 'applied index is now lower than readState.Index' (duration: 176.361077ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:33.354205Z","caller":"traceutil/trace.go:171","msg":"trace[2135714173] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"267.313053ms","start":"2026-09-19T01:07:33.086877Z","end":"2026-09-19T01:07:33.35419Z","steps":["trace[2135714173] 'process raft request' (duration: 90.863753ms)","trace[2135714173] 'compare' (duration: 175.659225ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:07:33.354543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.223863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-16ba873f-dce0-4e21-b59a-7fbc7c187e6a\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-09-19T01:07:33.354629Z","caller":"traceutil/trace.go:171","msg":"trace[1825871657] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-16ba873f-dce0-4e21-b59a-7fbc7c187e6a; range_end:; response_count:1; response_revision:5147; }","duration":"265.363556ms","start":"2026-09-19T01:07:33.089246Z","end":"2026-09-19T01:07:33.35461Z","steps":["trace[1825871657] 'agreement among raft nodes before linearized reading' (duration: 265.087251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:33.354542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.136917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-19T01:07:33.354768Z","caller":"traceutil/trace.go:171","msg":"trace[1734892249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5147; }","duration":"221.417913ms","start":"2026-09-19T01:07:33.133329Z","end":"2026-09-19T01:07:33.354747Z","steps":["trace[1734892249] 'agreement among raft nodes before linearized reading' (duration: 221.026076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:33.354867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.423476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-19T01:07:33.354996Z","caller":"traceutil/trace.go:171","msg":"trace[1677814130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5147; }","duration":"189.58792ms","start":"2026-09-19T01:07:33.165387Z","end":"2026-09-19T01:07:33.354975Z","steps":["trace[1677814130] 'agreement among raft nodes before linearized reading' (duration: 189.208733ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:36.531304Z","caller":"traceutil/trace.go:171","msg":"trace[251056428] linearizableReadLoop","detail":"{readStateIndex:5399; appliedIndex:5398; }","duration":"142.212581ms","start":"2026-09-19T01:07:36.389058Z","end":"2026-09-19T01:07:36.531271Z","steps":["trace[251056428] 'read index received' (duration: 141.854224ms)","trace[251056428] 'applied index is now lower than readState.Index' (duration: 356.737µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:36.53162Z","caller":"traceutil/trace.go:171","msg":"trace[1860990430] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"240.059469ms","start":"2026-09-19T01:07:36.291546Z","end":"2026-09-19T01:07:36.531605Z","steps":["trace[1860990430] 'process raft request' (duration: 239.473219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:36.532049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.927633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-16ba873f-dce0-4e21-b59a-7fbc7c187e6a\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-09-19T01:07:36.532196Z","caller":"traceutil/trace.go:171","msg":"trace[372004673] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-16ba873f-dce0-4e21-b59a-7fbc7c187e6a; range_end:; response_count:1; response_revision:5195; }","duration":"143.122867ms","start":"2026-09-19T01:07:36.389027Z","end":"2026-09-19T01:07:36.53215Z","steps":["trace[372004673] 'agreement among raft nodes before linearized reading' (duration: 142.792091ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:44.000327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-09-19T01:07:44.117867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"116.466205ms","hash":708813490,"current-db-size-bytes":51896320,"current-db-size":"52 MB","current-db-size-in-use-bytes":27209728,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-19T01:07:44.117925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":708813490,"revision":2877,"compact-revision":-1} {"level":"info","ts":"2026-09-19T01:07:46.775149Z","caller":"traceutil/trace.go:171","msg":"trace[1604510125] linearizableReadLoop","detail":"{readStateIndex:5537; appliedIndex:5536; }","duration":"139.627607ms","start":"2026-09-19T01:07:46.635498Z","end":"2026-09-19T01:07:46.775126Z","steps":["trace[1604510125] 'read index received' (duration: 139.463564ms)","trace[1604510125] 'applied index is now lower than readState.Index' (duration: 163.073µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:07:46.775225Z","caller":"traceutil/trace.go:171","msg":"trace[1310825932] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"165.638631ms","start":"2026-09-19T01:07:46.609559Z","end":"2026-09-19T01:07:46.775198Z","steps":["trace[1310825932] 'process raft request' (duration: 165.418177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:07:46.775345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.828761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T01:07:46.775382Z","caller":"traceutil/trace.go:171","msg":"trace[1065442290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5327; }","duration":"139.926742ms","start":"2026-09-19T01:07:46.635445Z","end":"2026-09-19T01:07:46.775371Z","steps":["trace[1065442290] 'agreement among raft nodes before linearized reading' (duration: 139.767619ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:07:47.159825Z","caller":"traceutil/trace.go:171","msg":"trace[699733409] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"106.720556ms","start":"2026-09-19T01:07:47.05308Z","end":"2026-09-19T01:07:47.159801Z","steps":["trace[699733409] 'process raft request' (duration: 83.567253ms)","trace[699733409] 'compare' (duration: 22.9588ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:31.838931Z","caller":"traceutil/trace.go:171","msg":"trace[1523482598] linearizableReadLoop","detail":"{readStateIndex:5926; appliedIndex:5925; }","duration":"109.230775ms","start":"2026-09-19T01:08:31.729668Z","end":"2026-09-19T01:08:31.838898Z","steps":["trace[1523482598] 'read index received' (duration: 108.962579ms)","trace[1523482598] 'applied index is now lower than readState.Index' (duration: 266.826µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:08:31.839024Z","caller":"traceutil/trace.go:171","msg":"trace[168938626] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"117.687008ms","start":"2026-09-19T01:08:31.721284Z","end":"2026-09-19T01:08:31.838971Z","steps":["trace[168938626] 'process raft request' (duration: 117.387412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:08:31.839536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.833935ms","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-09-19T01:08:31.839623Z","caller":"traceutil/trace.go:171","msg":"trace[2130603764] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5705; }","duration":"109.966708ms","start":"2026-09-19T01:08:31.729625Z","end":"2026-09-19T01:08:31.839592Z","steps":["trace[2130603764] 'agreement among raft nodes before linearized reading' (duration: 109.466349ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:08:33.505869Z","caller":"traceutil/trace.go:171","msg":"trace[744863742] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"179.516585ms","start":"2026-09-19T01:08:33.326328Z","end":"2026-09-19T01:08:33.505845Z","steps":["trace[744863742] 'process raft request' (duration: 169.660237ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:11:58.93294Z","caller":"traceutil/trace.go:171","msg":"trace[377665829] transaction","detail":"{read_only:false; response_revision:7155; number_of_response:1; }","duration":"140.599318ms","start":"2026-09-19T01:11:58.792318Z","end":"2026-09-19T01:11:58.932917Z","steps":["trace[377665829] 'process raft request' (duration: 140.259602ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:12:44.007327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5297} {"level":"info","ts":"2026-09-19T01:12:44.095386Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5297,"took":"86.16211ms","hash":2040402675,"current-db-size-bytes":51896320,"current-db-size":"52 MB","current-db-size-in-use-bytes":18878464,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T01:12:44.095503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2040402675,"revision":5297,"compact-revision":2877}