{"level":"warn","ts":"2026-04-10T13:45:49.863669Z","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-10T13:45:49.863746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.250: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.19.213.250:2380","--initial-cluster=instance=https://199.19.213.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.250: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-10T13:45:49.863812Z","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-10T13:45:49.863833Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.250:2380"]} {"level":"info","ts":"2026-04-10T13:45:49.863854Z","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-10T13:45:49.864489Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"]} {"level":"info","ts":"2026-04-10T13:45:49.864564Z","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.19.213.250:2380"],"listen-peer-urls":["https://199.19.213.250:2380"],"advertise-client-urls":["https://199.19.213.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.250: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-10T13:45:49.868365Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.594375ms"} {"level":"info","ts":"2026-04-10T13:45:49.876097Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1918dd9317ae83a2","cluster-id":"1b9b645507bfe47a"} {"level":"info","ts":"2026-04-10T13:45:49.876234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T13:45:49.876291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became follower at term 0"} {"level":"info","ts":"2026-04-10T13:45:49.876307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1918dd9317ae83a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T13:45:49.876341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became follower at term 1"} {"level":"info","ts":"2026-04-10T13:45:49.876398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=(1808438874216498082)"} {"level":"warn","ts":"2026-04-10T13:45:49.884649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T13:45:49.886375Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T13:45:49.887885Z","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-10T13:45:49.890556Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1918dd9317ae83a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T13:45:49.891027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1918dd9317ae83a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T13:45:49.891126Z","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-10T13:45:49.891292Z","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-10T13:45:49.891353Z","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-10T13:45:49.89164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=(1808438874216498082)"} {"level":"info","ts":"2026-04-10T13:45:49.891795Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1b9b645507bfe47a","local-member-id":"1918dd9317ae83a2","added-peer-id":"1918dd9317ae83a2","added-peer-peer-urls":["https://199.19.213.250:2380"]} {"level":"info","ts":"2026-04-10T13:45:49.895489Z","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-10T13:45:49.895699Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.250:2380"} {"level":"info","ts":"2026-04-10T13:45:49.895905Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.250:2380"} {"level":"info","ts":"2026-04-10T13:45:49.896764Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1918dd9317ae83a2","initial-advertise-peer-urls":["https://199.19.213.250:2380"],"listen-peer-urls":["https://199.19.213.250:2380"],"advertise-client-urls":["https://199.19.213.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T13:45:49.896801Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T13:45:50.177579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T13:45:50.177634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T13:45:50.177729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 received MsgPreVoteResp from 1918dd9317ae83a2 at term 1"} {"level":"info","ts":"2026-04-10T13:45:50.177756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became candidate at term 2"} {"level":"info","ts":"2026-04-10T13:45:50.177764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 received MsgVoteResp from 1918dd9317ae83a2 at term 2"} {"level":"info","ts":"2026-04-10T13:45:50.177795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became leader at term 2"} {"level":"info","ts":"2026-04-10T13:45:50.177812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1918dd9317ae83a2 elected leader 1918dd9317ae83a2 at term 2"} {"level":"info","ts":"2026-04-10T13:45:50.179495Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:50.181851Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1918dd9317ae83a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.250:2379]}","request-path":"/0/members/1918dd9317ae83a2/attributes","cluster-id":"1b9b645507bfe47a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T13:45:50.181912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T13:45:50.181912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T13:45:50.182297Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T13:45:50.182363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T13:45:50.183128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1b9b645507bfe47a","local-member-id":"1918dd9317ae83a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:50.183266Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:50.1833Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T13:45:50.184756Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T13:45:50.18644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.250:2379"} {"level":"info","ts":"2026-04-10T13:46:16.40598Z","caller":"traceutil/trace.go:171","msg":"trace[139183107] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"177.657778ms","start":"2026-04-10T13:46:16.228285Z","end":"2026-04-10T13:46:16.405943Z","steps":["trace[139183107] 'process raft request' (duration: 177.448363ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:24.592408Z","caller":"traceutil/trace.go:171","msg":"trace[936956070] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"144.415455ms","start":"2026-04-10T13:46:24.447957Z","end":"2026-04-10T13:46:24.592372Z","steps":["trace[936956070] 'process raft request' (duration: 144.215749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:24.868899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.507637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:46:24.868995Z","caller":"traceutil/trace.go:171","msg":"trace[670141570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"148.659671ms","start":"2026-04-10T13:46:24.720318Z","end":"2026-04-10T13:46:24.868978Z","steps":["trace[670141570] 'range keys from in-memory index tree' (duration: 148.411985ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:30.604963Z","caller":"traceutil/trace.go:171","msg":"trace[309453186] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"153.634068ms","start":"2026-04-10T13:46:30.451302Z","end":"2026-04-10T13:46:30.604936Z","steps":["trace[309453186] 'process raft request' (duration: 153.452114ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:30.768438Z","caller":"traceutil/trace.go:171","msg":"trace[960994534] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"146.660181ms","start":"2026-04-10T13:46:30.621752Z","end":"2026-04-10T13:46:30.768412Z","steps":["trace[960994534] 'process raft request' (duration: 143.553863ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:30.971176Z","caller":"traceutil/trace.go:171","msg":"trace[657119582] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"348.717621ms","start":"2026-04-10T13:46:30.622424Z","end":"2026-04-10T13:46:30.971142Z","steps":["trace[657119582] 'process raft request' (duration: 248.918462ms)","trace[657119582] 'compare' (duration: 99.508602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:46:30.971838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:30.622411Z","time spent":"348.840835ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:46:30.973677Z","caller":"traceutil/trace.go:171","msg":"trace[1682212851] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"225.705386ms","start":"2026-04-10T13:46:30.747947Z","end":"2026-04-10T13:46:30.973652Z","steps":["trace[1682212851] 'process raft request' (duration: 223.84976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:57.23403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.982174ms","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-10T13:46:57.234121Z","caller":"traceutil/trace.go:171","msg":"trace[772064611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"337.140509ms","start":"2026-04-10T13:46:56.896963Z","end":"2026-04-10T13:46:57.234104Z","steps":["trace[772064611] 'range keys from in-memory index tree' (duration: 336.80158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:57.234158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:46:56.89694Z","time spent":"337.21062ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T13:46:57.234186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.90801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T13:46:57.234288Z","caller":"traceutil/trace.go:171","msg":"trace[385643171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"134.016703ms","start":"2026-04-10T13:46:57.100249Z","end":"2026-04-10T13:46:57.234266Z","steps":["trace[385643171] 'range keys from in-memory index tree' (duration: 133.696345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:46:57.234508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.706334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-10T13:46:57.234587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.112787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T13:46:57.234598Z","caller":"traceutil/trace.go:171","msg":"trace[896002628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"129.836317ms","start":"2026-04-10T13:46:57.104737Z","end":"2026-04-10T13:46:57.234573Z","steps":["trace[896002628] 'range keys from in-memory index tree' (duration: 129.494508ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:46:57.234748Z","caller":"traceutil/trace.go:171","msg":"trace[1247238566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"205.286781ms","start":"2026-04-10T13:46:57.029391Z","end":"2026-04-10T13:46:57.234678Z","steps":["trace[1247238566] 'range keys from in-memory index tree' (duration: 204.911882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:07.490757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.265224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcdedce3-7088-4ae5-984c-581da4517bcd\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-10T13:48:07.490869Z","caller":"traceutil/trace.go:171","msg":"trace[1358793237] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcdedce3-7088-4ae5-984c-581da4517bcd; range_end:; response_count:1; response_revision:1873; }","duration":"218.446038ms","start":"2026-04-10T13:48:07.272397Z","end":"2026-04-10T13:48:07.490844Z","steps":["trace[1358793237] 'range keys from in-memory index tree' (duration: 218.014217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:08.154599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.958539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcdedce3-7088-4ae5-984c-581da4517bcd\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-10T13:48:08.154678Z","caller":"traceutil/trace.go:171","msg":"trace[563921443] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcdedce3-7088-4ae5-984c-581da4517bcd; range_end:; response_count:1; response_revision:1877; }","duration":"202.090363ms","start":"2026-04-10T13:48:07.952572Z","end":"2026-04-10T13:48:08.154663Z","steps":["trace[563921443] 'range keys from in-memory index tree' (duration: 201.797785ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:08.17364Z","caller":"traceutil/trace.go:171","msg":"trace[1215036582] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"145.987665ms","start":"2026-04-10T13:48:08.027626Z","end":"2026-04-10T13:48:08.173614Z","steps":["trace[1215036582] 'read index received' (duration: 145.947924ms)","trace[1215036582] 'applied index is now lower than readState.Index' (duration: 38.721µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:48:08.173706Z","caller":"traceutil/trace.go:171","msg":"trace[2022895789] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"167.289024ms","start":"2026-04-10T13:48:08.006393Z","end":"2026-04-10T13:48:08.173682Z","steps":["trace[2022895789] 'process raft request' (duration: 167.079428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:08.174119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.426677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-10T13:48:08.174249Z","caller":"traceutil/trace.go:171","msg":"trace[1357329443] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1878; }","duration":"146.646893ms","start":"2026-04-10T13:48:08.027568Z","end":"2026-04-10T13:48:08.174214Z","steps":["trace[1357329443] 'agreement among raft nodes before linearized reading' (duration: 146.15589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:20.928416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.288594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:48:20.928559Z","caller":"traceutil/trace.go:171","msg":"trace[886363391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1979; }","duration":"375.52861ms","start":"2026-04-10T13:48:20.553011Z","end":"2026-04-10T13:48:20.928539Z","steps":["trace[886363391] 'count revisions from in-memory index tree' (duration: 375.093778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:20.928614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:48:20.55299Z","time spent":"375.610392ms","remote":"127.0.0.1:60396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-10T13:48:20.928881Z","caller":"traceutil/trace.go:171","msg":"trace[1962420543] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"288.061266ms","start":"2026-04-10T13:48:20.640765Z","end":"2026-04-10T13:48:20.928827Z","steps":["trace[1962420543] 'process raft request' (duration: 283.088473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:20.928984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.560145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:48:20.92907Z","caller":"traceutil/trace.go:171","msg":"trace[1999692515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"208.695998ms","start":"2026-04-10T13:48:20.720356Z","end":"2026-04-10T13:48:20.929052Z","steps":["trace[1999692515] 'agreement among raft nodes before linearized reading' (duration: 208.538674ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:20.928857Z","caller":"traceutil/trace.go:171","msg":"trace[675543852] linearizableReadLoop","detail":"{readStateIndex:2053; appliedIndex:2052; }","duration":"208.386131ms","start":"2026-04-10T13:48:20.720416Z","end":"2026-04-10T13:48:20.928803Z","steps":["trace[675543852] 'read index received' (duration: 203.414158ms)","trace[675543852] 'applied index is now lower than readState.Index' (duration: 4.970592ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:48:47.133785Z","caller":"traceutil/trace.go:171","msg":"trace[839295998] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"110.116262ms","start":"2026-04-10T13:48:47.023644Z","end":"2026-04-10T13:48:47.13376Z","steps":["trace[839295998] 'process raft request' (duration: 109.974938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:52.91415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.352199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:48:52.914272Z","caller":"traceutil/trace.go:171","msg":"trace[2122400123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2168; }","duration":"196.504732ms","start":"2026-04-10T13:48:52.717742Z","end":"2026-04-10T13:48:52.914247Z","steps":["trace[2122400123] 'range keys from in-memory index tree' (duration: 196.263276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:59.419783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.087829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:48:59.420106Z","caller":"traceutil/trace.go:171","msg":"trace[1999681118] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"386.348218ms","start":"2026-04-10T13:48:59.03373Z","end":"2026-04-10T13:48:59.420078Z","steps":["trace[1999681118] 'process raft request' (duration: 46.702644ms)","trace[1999681118] 'compare' (duration: 338.926075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:48:59.420185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:48:59.033704Z","time spent":"386.44812ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:48:59.434391Z","caller":"traceutil/trace.go:171","msg":"trace[70901253] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"216.808833ms","start":"2026-04-10T13:48:59.217544Z","end":"2026-04-10T13:48:59.434353Z","steps":["trace[70901253] 'process raft request' (duration: 216.642698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:59.905251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.152457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:48:59.90582Z","caller":"traceutil/trace.go:171","msg":"trace[1208152808] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"672.675092ms","start":"2026-04-10T13:48:59.233104Z","end":"2026-04-10T13:48:59.905779Z","steps":["trace[1208152808] 'process raft request' (duration: 399.883328ms)","trace[1208152808] 'compare' (duration: 271.606393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:48:59.906003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:48:59.233077Z","time spent":"672.830276ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:48:59.905837Z","caller":"traceutil/trace.go:171","msg":"trace[590045182] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"653.321546ms","start":"2026-04-10T13:48:59.252491Z","end":"2026-04-10T13:48:59.905813Z","steps":["trace[590045182] 'process raft request' (duration: 653.185103ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:48:59.906158Z","caller":"traceutil/trace.go:171","msg":"trace[1639735195] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"652.007472ms","start":"2026-04-10T13:48:59.254138Z","end":"2026-04-10T13:48:59.906145Z","steps":["trace[1639735195] 'process raft request' (duration: 651.623842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:59.906222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:48:59.254122Z","time spent":"652.069563ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:48:59.906227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:48:59.252472Z","time spent":"653.694857ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:48:59.906294Z","caller":"traceutil/trace.go:171","msg":"trace[659517908] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2284; }","duration":"350.0256ms","start":"2026-04-10T13:48:59.556203Z","end":"2026-04-10T13:48:59.906229Z","steps":["trace[659517908] 'read index received' (duration: 76.772284ms)","trace[659517908] 'applied index is now lower than readState.Index' (duration: 273.086672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:48:59.906617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.39442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-10T13:48:59.906709Z","caller":"traceutil/trace.go:171","msg":"trace[864654038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"350.532745ms","start":"2026-04-10T13:48:59.556151Z","end":"2026-04-10T13:48:59.906684Z","steps":["trace[864654038] 'agreement among raft nodes before linearized reading' (duration: 350.274608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:48:59.906717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.88191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T13:48:59.906757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:48:59.556129Z","time spent":"350.618786ms","remote":"127.0.0.1:48780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-10T13:48:59.906774Z","caller":"traceutil/trace.go:171","msg":"trace[450650172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2205; }","duration":"185.965082ms","start":"2026-04-10T13:48:59.720793Z","end":"2026-04-10T13:48:59.906758Z","steps":["trace[450650172] 'agreement among raft nodes before linearized reading' (duration: 185.883389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:50:23.520421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.222228ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:50:23.521167Z","caller":"traceutil/trace.go:171","msg":"trace[2061177887] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2850; }","duration":"246.994249ms","start":"2026-04-10T13:50:23.274152Z","end":"2026-04-10T13:50:23.521146Z","steps":["trace[2061177887] 'range keys from in-memory index tree' (duration: 246.174407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:50:23.829428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.165711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:50:23.829532Z","caller":"traceutil/trace.go:171","msg":"trace[1566047445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2850; }","duration":"107.319746ms","start":"2026-04-10T13:50:23.722188Z","end":"2026-04-10T13:50:23.829507Z","steps":["trace[1566047445] 'range keys from in-memory index tree' (duration: 107.076709ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:50:23.830329Z","caller":"traceutil/trace.go:171","msg":"trace[541766187] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"107.85067ms","start":"2026-04-10T13:50:23.722447Z","end":"2026-04-10T13:50:23.830298Z","steps":["trace[541766187] 'process raft request' (duration: 14.438103ms)","trace[541766187] 'compare' (duration: 92.814661ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:52:59.208812Z","caller":"traceutil/trace.go:171","msg":"trace[249769132] transaction","detail":"{read_only:false; response_revision:3870; number_of_response:1; }","duration":"371.25433ms","start":"2026-04-10T13:52:58.837516Z","end":"2026-04-10T13:52:59.20877Z","steps":["trace[249769132] 'process raft request' (duration: 312.017448ms)","trace[249769132] 'compare' (duration: 58.580644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:52:59.209164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:52:58.837484Z","time spent":"371.448115ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:52:59.325155Z","caller":"traceutil/trace.go:171","msg":"trace[9439394] transaction","detail":"{read_only:false; response_revision:3871; number_of_response:1; }","duration":"485.92883ms","start":"2026-04-10T13:52:58.839182Z","end":"2026-04-10T13:52:59.325111Z","steps":["trace[9439394] 'process raft request' (duration: 470.121218ms)","trace[9439394] 'compare' (duration: 15.486973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:52:59.325359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:52:58.839168Z","time spent":"486.079694ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T13:52:59.327221Z","caller":"traceutil/trace.go:171","msg":"trace[1086070685] linearizableReadLoop","detail":"{readStateIndex:4017; appliedIndex:4014; }","duration":"389.226469ms","start":"2026-04-10T13:52:58.937966Z","end":"2026-04-10T13:52:59.327192Z","steps":["trace[1086070685] 'read index received' (duration: 211.585658ms)","trace[1086070685] 'applied index is now lower than readState.Index' (duration: 177.639771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:52:59.327556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.986032ms","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-10T13:52:59.327585Z","caller":"traceutil/trace.go:171","msg":"trace[633125706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3871; }","duration":"117.099195ms","start":"2026-04-10T13:52:59.210477Z","end":"2026-04-10T13:52:59.327576Z","steps":["trace[633125706] 'agreement among raft nodes before linearized reading' (duration: 116.943781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:52:59.327627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.64552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-10T13:52:59.327694Z","caller":"traceutil/trace.go:171","msg":"trace[125035416] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3871; }","duration":"389.778773ms","start":"2026-04-10T13:52:58.937901Z","end":"2026-04-10T13:52:59.32768Z","steps":["trace[125035416] 'agreement among raft nodes before linearized reading' (duration: 389.429094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:52:59.327736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:52:58.937878Z","time spent":"389.847755ms","remote":"127.0.0.1:33134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":378,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-10T13:54:25.521118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.77783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:54:25.521227Z","caller":"traceutil/trace.go:171","msg":"trace[70512456] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5064; }","duration":"140.970975ms","start":"2026-04-10T13:54:25.380235Z","end":"2026-04-10T13:54:25.521206Z","steps":["trace[70512456] 'range keys from in-memory index tree' (duration: 140.671428ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:25.698079Z","caller":"traceutil/trace.go:171","msg":"trace[1286679050] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5064; }","duration":"147.080577ms","start":"2026-04-10T13:54:25.55096Z","end":"2026-04-10T13:54:25.69804Z","steps":["trace[1286679050] 'process raft request' (duration: 146.965234ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:25.751806Z","caller":"traceutil/trace.go:171","msg":"trace[578961628] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"105.227215ms","start":"2026-04-10T13:54:25.646548Z","end":"2026-04-10T13:54:25.751775Z","steps":["trace[578961628] 'process raft request' (duration: 105.135893ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:25.751862Z","caller":"traceutil/trace.go:171","msg":"trace[1252145993] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"106.248233ms","start":"2026-04-10T13:54:25.645553Z","end":"2026-04-10T13:54:25.751801Z","steps":["trace[1252145993] 'process raft request' (duration: 105.962105ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:26.068283Z","caller":"traceutil/trace.go:171","msg":"trace[1707760129] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"148.358132ms","start":"2026-04-10T13:54:25.919843Z","end":"2026-04-10T13:54:26.068201Z","steps":["trace[1707760129] 'process raft request' (duration: 87.246138ms)","trace[1707760129] 'compare' (duration: 60.873047ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:26.309692Z","caller":"traceutil/trace.go:171","msg":"trace[46136442] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"174.998839ms","start":"2026-04-10T13:54:26.134669Z","end":"2026-04-10T13:54:26.309668Z","steps":["trace[46136442] 'process raft request' (duration: 174.812454ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:26.330029Z","caller":"traceutil/trace.go:171","msg":"trace[244967420] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"104.024744ms","start":"2026-04-10T13:54:26.225968Z","end":"2026-04-10T13:54:26.329993Z","steps":["trace[244967420] 'process raft request' (duration: 101.866487ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:37.246637Z","caller":"traceutil/trace.go:171","msg":"trace[706975385] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"119.795233ms","start":"2026-04-10T13:54:37.126805Z","end":"2026-04-10T13:54:37.2466Z","steps":["trace[706975385] 'process raft request' (duration: 119.500986ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:37.247059Z","caller":"traceutil/trace.go:171","msg":"trace[651274632] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"114.532583ms","start":"2026-04-10T13:54:37.132492Z","end":"2026-04-10T13:54:37.247025Z","steps":["trace[651274632] 'process raft request' (duration: 114.032069ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:37.389287Z","caller":"traceutil/trace.go:171","msg":"trace[989663144] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"102.776201ms","start":"2026-04-10T13:54:37.286472Z","end":"2026-04-10T13:54:37.389248Z","steps":["trace[989663144] 'process raft request' (duration: 55.863904ms)","trace[989663144] 'compare' (duration: 46.637119ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:37.955669Z","caller":"traceutil/trace.go:171","msg":"trace[2083213743] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"160.881595ms","start":"2026-04-10T13:54:37.794756Z","end":"2026-04-10T13:54:37.955638Z","steps":["trace[2083213743] 'process raft request' (duration: 160.653419ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:37.956911Z","caller":"traceutil/trace.go:171","msg":"trace[1544591784] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"151.338911ms","start":"2026-04-10T13:54:37.80555Z","end":"2026-04-10T13:54:37.956889Z","steps":["trace[1544591784] 'process raft request' (duration: 150.566201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:54:45.994494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.71261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:54:45.99464Z","caller":"traceutil/trace.go:171","msg":"trace[842060330] linearizableReadLoop","detail":"{readStateIndex:5683; appliedIndex:5682; }","duration":"303.600955ms","start":"2026-04-10T13:54:45.691016Z","end":"2026-04-10T13:54:45.994617Z","steps":["trace[842060330] 'read index received' (duration: 56.681µs)","trace[842060330] 'applied index is now lower than readState.Index' (duration: 303.542434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:54:45.995118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.105939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c7f37139-a2c0-4bff-869f-8c57bc7b1b39\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-10T13:54:45.995167Z","caller":"traceutil/trace.go:171","msg":"trace[429713224] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c7f37139-a2c0-4bff-869f-8c57bc7b1b39; range_end:; response_count:1; response_revision:5478; }","duration":"304.218042ms","start":"2026-04-10T13:54:45.690936Z","end":"2026-04-10T13:54:45.995154Z","steps":["trace[429713224] 'agreement among raft nodes before linearized reading' (duration: 303.741369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:54:45.99521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:54:45.690905Z","time spent":"304.295343ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c7f37139-a2c0-4bff-869f-8c57bc7b1b39\" "} {"level":"info","ts":"2026-04-10T13:54:45.994761Z","caller":"traceutil/trace.go:171","msg":"trace[376543917] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"492.104474ms","start":"2026-04-10T13:54:45.502618Z","end":"2026-04-10T13:54:45.994723Z","steps":["trace[376543917] 'process raft request' (duration: 101.052055ms)","trace[376543917] 'compare' (duration: 390.431952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T13:54:45.995561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T13:54:45.502593Z","time spent":"492.849594ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T13:54:46.225317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.705885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T13:54:46.225673Z","caller":"traceutil/trace.go:171","msg":"trace[1716659382] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"148.254369ms","start":"2026-04-10T13:54:46.077372Z","end":"2026-04-10T13:54:46.225627Z","steps":["trace[1716659382] 'process raft request' (duration: 38.143393ms)","trace[1716659382] 'compare' (duration: 109.565241ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:46.225803Z","caller":"traceutil/trace.go:171","msg":"trace[163324909] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"147.869129ms","start":"2026-04-10T13:54:46.0779Z","end":"2026-04-10T13:54:46.225769Z","steps":["trace[163324909] 'process raft request' (duration: 147.731835ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:46.225935Z","caller":"traceutil/trace.go:171","msg":"trace[280286466] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"148.540507ms","start":"2026-04-10T13:54:46.077374Z","end":"2026-04-10T13:54:46.225915Z","steps":["trace[280286466] 'process raft request' (duration: 148.052714ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:54:46.226Z","caller":"traceutil/trace.go:171","msg":"trace[2135564375] linearizableReadLoop","detail":"{readStateIndex:5689; appliedIndex:5685; }","duration":"146.672918ms","start":"2026-04-10T13:54:46.079316Z","end":"2026-04-10T13:54:46.225989Z","steps":["trace[2135564375] 'read index received' (duration: 36.182742ms)","trace[2135564375] 'applied index is now lower than readState.Index' (duration: 110.488636ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:54:46.22596Z","caller":"traceutil/trace.go:171","msg":"trace[2009812651] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"148.033584ms","start":"2026-04-10T13:54:46.077916Z","end":"2026-04-10T13:54:46.22595Z","steps":["trace[2009812651] 'process raft request' (duration: 147.766116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:54:46.226463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.135779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9s7wx\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-10T13:54:46.226499Z","caller":"traceutil/trace.go:171","msg":"trace[1934235580] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9s7wx; range_end:; response_count:1; response_revision:5485; }","duration":"147.220152ms","start":"2026-04-10T13:54:46.07927Z","end":"2026-04-10T13:54:46.22649Z","steps":["trace[1934235580] 'agreement among raft nodes before linearized reading' (duration: 146.800721ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:55:50.570305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-04-10T13:55:50.849084Z","caller":"traceutil/trace.go:171","msg":"trace[284431397] transaction","detail":"{read_only:false; response_revision:6078; number_of_response:1; }","duration":"143.120765ms","start":"2026-04-10T13:55:50.705896Z","end":"2026-04-10T13:55:50.849017Z","steps":["trace[284431397] 'process raft request' (duration: 64.15244ms)","trace[284431397] 'compare' (duration: 48.532844ms)","trace[284431397] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 30.296488ms)"],"step_count":3} {"level":"info","ts":"2026-04-10T13:55:50.849425Z","caller":"traceutil/trace.go:171","msg":"trace[173586306] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"143.228108ms","start":"2026-04-10T13:55:50.706157Z","end":"2026-04-10T13:55:50.849385Z","steps":["trace[173586306] 'process raft request' (duration: 142.989862ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:55:50.849494Z","caller":"traceutil/trace.go:171","msg":"trace[717681654] linearizableReadLoop","detail":"{readStateIndex:6301; appliedIndex:6298; }","duration":"142.216192ms","start":"2026-04-10T13:55:50.707261Z","end":"2026-04-10T13:55:50.849477Z","steps":["trace[717681654] 'read index received' (duration: 62.803295ms)","trace[717681654] 'applied index is now lower than readState.Index' (duration: 79.411066ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T13:55:50.849584Z","caller":"traceutil/trace.go:171","msg":"trace[920959265] transaction","detail":"{read_only:false; response_revision:6080; number_of_response:1; }","duration":"143.370832ms","start":"2026-04-10T13:55:50.706202Z","end":"2026-04-10T13:55:50.849573Z","steps":["trace[920959265] 'process raft request' (duration: 143.132546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:55:50.850014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.728015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:12 size:109144"} {"level":"info","ts":"2026-04-10T13:55:50.8501Z","caller":"traceutil/trace.go:171","msg":"trace[1223042916] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:12; response_revision:6080; }","duration":"142.887759ms","start":"2026-04-10T13:55:50.707192Z","end":"2026-04-10T13:55:50.85008Z","steps":["trace[1223042916] 'agreement among raft nodes before linearized reading' (duration: 142.442597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:55:50.857243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.767122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:55:50.857295Z","caller":"traceutil/trace.go:171","msg":"trace[2097880531] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:6081; }","duration":"116.859516ms","start":"2026-04-10T13:55:50.740424Z","end":"2026-04-10T13:55:50.857283Z","steps":["trace[2097880531] 'agreement among raft nodes before linearized reading' (duration: 116.761003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T13:55:50.857354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.28542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T13:55:50.857439Z","caller":"traceutil/trace.go:171","msg":"trace[346716748] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:6081; }","duration":"116.430004ms","start":"2026-04-10T13:55:50.740988Z","end":"2026-04-10T13:55:50.857418Z","steps":["trace[346716748] 'agreement among raft nodes before linearized reading' (duration: 116.26901ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T13:55:50.882905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"310.436106ms","hash":4035942058,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":33660928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-10T13:55:50.883001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4035942058,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-04-10T14:00:50.57973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6076} {"level":"info","ts":"2026-04-10T14:00:50.684552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6076,"took":"101.985012ms","hash":2566213098,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":21712896,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-10T14:00:50.684632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2566213098,"revision":6076,"compact-revision":3000} {"level":"info","ts":"2026-04-10T14:01:10.903279Z","caller":"traceutil/trace.go:171","msg":"trace[1986055760] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"447.708841ms","start":"2026-04-10T14:01:10.455533Z","end":"2026-04-10T14:01:10.903242Z","steps":["trace[1986055760] 'process raft request' (duration: 447.430143ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:10.903376Z","caller":"traceutil/trace.go:171","msg":"trace[88848664] linearizableReadLoop","detail":"{readStateIndex:8698; appliedIndex:8698; }","duration":"446.786307ms","start":"2026-04-10T14:01:10.456554Z","end":"2026-04-10T14:01:10.90334Z","steps":["trace[88848664] 'read index received' (duration: 446.770986ms)","trace[88848664] 'applied index is now lower than readState.Index' (duration: 12.29µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:01:10.903533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:01:10.455512Z","time spent":"447.893966ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T14:01:10.903612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.040003ms","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-10T14:01:10.903663Z","caller":"traceutil/trace.go:171","msg":"trace[1235517958] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8399; }","duration":"447.131676ms","start":"2026-04-10T14:01:10.456519Z","end":"2026-04-10T14:01:10.903651Z","steps":["trace[1235517958] 'agreement among raft nodes before linearized reading' (duration: 446.981332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:10.903707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T14:01:10.456509Z","time spent":"447.189307ms","remote":"127.0.0.1:33448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-10T14:01:10.917532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.754316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T14:01:10.917591Z","caller":"traceutil/trace.go:171","msg":"trace[1154718837] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8400; }","duration":"198.883939ms","start":"2026-04-10T14:01:10.718696Z","end":"2026-04-10T14:01:10.91758Z","steps":["trace[1154718837] 'agreement among raft nodes before linearized reading' (duration: 198.730455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:10.917567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.997505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-10T14:01:10.917777Z","caller":"traceutil/trace.go:171","msg":"trace[700370711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8400; }","duration":"298.21135ms","start":"2026-04-10T14:01:10.619496Z","end":"2026-04-10T14:01:10.917707Z","steps":["trace[700370711] 'agreement among raft nodes before linearized reading' (duration: 297.903542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:10.917809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.114141ms","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-10T14:01:10.917867Z","caller":"traceutil/trace.go:171","msg":"trace[591232457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8400; }","duration":"297.200983ms","start":"2026-04-10T14:01:10.620653Z","end":"2026-04-10T14:01:10.917854Z","steps":["trace[591232457] 'agreement among raft nodes before linearized reading' (duration: 296.819723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:10.917873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.371034ms","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-10T14:01:10.917911Z","caller":"traceutil/trace.go:171","msg":"trace[1539670795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8400; }","duration":"298.465037ms","start":"2026-04-10T14:01:10.619436Z","end":"2026-04-10T14:01:10.917901Z","steps":["trace[1539670795] 'agreement among raft nodes before linearized reading' (duration: 297.937463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:10.918052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.022695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T14:01:10.918119Z","caller":"traceutil/trace.go:171","msg":"trace[1894932702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8400; }","duration":"298.086517ms","start":"2026-04-10T14:01:10.620014Z","end":"2026-04-10T14:01:10.918101Z","steps":["trace[1894932702] 'agreement among raft nodes before linearized reading' (duration: 297.949223ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:11.84066Z","caller":"traceutil/trace.go:171","msg":"trace[718117100] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"273.231304ms","start":"2026-04-10T14:01:11.567389Z","end":"2026-04-10T14:01:11.84062Z","steps":["trace[718117100] 'process raft request' (duration: 273.012588ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:11.857401Z","caller":"traceutil/trace.go:171","msg":"trace[2107341761] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"287.451563ms","start":"2026-04-10T14:01:11.569918Z","end":"2026-04-10T14:01:11.857369Z","steps":["trace[2107341761] 'process raft request' (duration: 287.186126ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:11.857642Z","caller":"traceutil/trace.go:171","msg":"trace[2026122972] linearizableReadLoop","detail":"{readStateIndex:8705; appliedIndex:8703; }","duration":"146.798658ms","start":"2026-04-10T14:01:11.710804Z","end":"2026-04-10T14:01:11.857603Z","steps":["trace[2026122972] 'read index received' (duration: 129.611589ms)","trace[2026122972] 'applied index is now lower than readState.Index' (duration: 17.184089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:01:11.857889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.701306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:01:11.857941Z","caller":"traceutil/trace.go:171","msg":"trace[1334370350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8407; }","duration":"137.823729ms","start":"2026-04-10T14:01:11.720099Z","end":"2026-04-10T14:01:11.857922Z","steps":["trace[1334370350] 'agreement among raft nodes before linearized reading' (duration: 137.694295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:11.858063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.20649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-98f95cb8c-fjt6w.18a503a8d3a50e46\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-04-10T14:01:11.858231Z","caller":"traceutil/trace.go:171","msg":"trace[929914849] range","detail":"{range_begin:/registry/events/openstack/barbican-api-98f95cb8c-fjt6w.18a503a8d3a50e46; range_end:; response_count:1; response_revision:8407; }","duration":"147.466416ms","start":"2026-04-10T14:01:11.710731Z","end":"2026-04-10T14:01:11.858197Z","steps":["trace[929914849] 'agreement among raft nodes before linearized reading' (duration: 146.981583ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:13.133651Z","caller":"traceutil/trace.go:171","msg":"trace[1897074861] linearizableReadLoop","detail":"{readStateIndex:8711; appliedIndex:8710; }","duration":"150.363993ms","start":"2026-04-10T14:01:12.983245Z","end":"2026-04-10T14:01:13.133609Z","steps":["trace[1897074861] 'read index received' (duration: 148.905144ms)","trace[1897074861] 'applied index is now lower than readState.Index' (duration: 1.456389ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T14:01:13.133774Z","caller":"traceutil/trace.go:171","msg":"trace[2022916413] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"166.71093ms","start":"2026-04-10T14:01:12.967009Z","end":"2026-04-10T14:01:13.13372Z","steps":["trace[2022916413] 'process raft request' (duration: 165.150839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:13.134089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.751074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-10T14:01:13.134091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.543261ms","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-10T14:01:13.134171Z","caller":"traceutil/trace.go:171","msg":"trace[1635711504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8412; }","duration":"150.900678ms","start":"2026-04-10T14:01:12.983252Z","end":"2026-04-10T14:01:13.134153Z","steps":["trace[1635711504] 'agreement among raft nodes before linearized reading' (duration: 150.60089ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:13.134182Z","caller":"traceutil/trace.go:171","msg":"trace[1342182478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8412; }","duration":"149.649044ms","start":"2026-04-10T14:01:12.984515Z","end":"2026-04-10T14:01:13.134164Z","steps":["trace[1342182478] 'agreement among raft nodes before linearized reading' (duration: 149.425998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T14:01:13.134188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.905478ms","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-10T14:01:13.134241Z","caller":"traceutil/trace.go:171","msg":"trace[124118682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8412; }","duration":"151.012531ms","start":"2026-04-10T14:01:12.983216Z","end":"2026-04-10T14:01:13.134229Z","steps":["trace[124118682] 'agreement among raft nodes before linearized reading' (duration: 150.59541ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:40.667298Z","caller":"traceutil/trace.go:171","msg":"trace[1834774427] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"163.36001ms","start":"2026-04-10T14:01:40.5039Z","end":"2026-04-10T14:01:40.66726Z","steps":["trace[1834774427] 'process raft request' (duration: 163.169055ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:40.85602Z","caller":"traceutil/trace.go:171","msg":"trace[2058421757] linearizableReadLoop","detail":"{readStateIndex:8921; appliedIndex:8920; }","duration":"134.756262ms","start":"2026-04-10T14:01:40.721234Z","end":"2026-04-10T14:01:40.85599Z","steps":["trace[2058421757] 'read index received' (duration: 126.313695ms)","trace[2058421757] 'applied index is now lower than readState.Index' (duration: 8.441357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:01:40.856187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.942116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T14:01:40.85623Z","caller":"traceutil/trace.go:171","msg":"trace[753545039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8614; }","duration":"135.06301ms","start":"2026-04-10T14:01:40.721157Z","end":"2026-04-10T14:01:40.85622Z","steps":["trace[753545039] 'agreement among raft nodes before linearized reading' (duration: 134.935347ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:40.856529Z","caller":"traceutil/trace.go:171","msg":"trace[62675078] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"271.820916ms","start":"2026-04-10T14:01:40.584663Z","end":"2026-04-10T14:01:40.856484Z","steps":["trace[62675078] 'process raft request' (duration: 262.978279ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:53.528097Z","caller":"traceutil/trace.go:171","msg":"trace[2011182577] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"166.562987ms","start":"2026-04-10T14:01:53.361504Z","end":"2026-04-10T14:01:53.528067Z","steps":["trace[2011182577] 'process raft request' (duration: 166.372951ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:53.539651Z","caller":"traceutil/trace.go:171","msg":"trace[656696827] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"176.260978ms","start":"2026-04-10T14:01:53.36335Z","end":"2026-04-10T14:01:53.539611Z","steps":["trace[656696827] 'process raft request' (duration: 175.97952ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:53.539925Z","caller":"traceutil/trace.go:171","msg":"trace[73540529] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"176.400582ms","start":"2026-04-10T14:01:53.363503Z","end":"2026-04-10T14:01:53.539904Z","steps":["trace[73540529] 'process raft request' (duration: 176.261578ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:53.539926Z","caller":"traceutil/trace.go:171","msg":"trace[1546568478] transaction","detail":"{read_only:false; response_revision:8726; number_of_response:1; }","duration":"176.427872ms","start":"2026-04-10T14:01:53.363447Z","end":"2026-04-10T14:01:53.539875Z","steps":["trace[1546568478] 'process raft request' (duration: 176.106353ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:54.199234Z","caller":"traceutil/trace.go:171","msg":"trace[1855891916] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"186.336288ms","start":"2026-04-10T14:01:54.012873Z","end":"2026-04-10T14:01:54.199209Z","steps":["trace[1855891916] 'process raft request' (duration: 186.173844ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:54.19926Z","caller":"traceutil/trace.go:171","msg":"trace[1046327435] linearizableReadLoop","detail":"{readStateIndex:9042; appliedIndex:9041; }","duration":"181.082887ms","start":"2026-04-10T14:01:54.018127Z","end":"2026-04-10T14:01:54.19921Z","steps":["trace[1046327435] 'read index received' (duration: 181.051566ms)","trace[1046327435] 'applied index is now lower than readState.Index' (duration: 27.291µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T14:01:54.199618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.462067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-10T14:01:54.199669Z","caller":"traceutil/trace.go:171","msg":"trace[920243662] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8729; }","duration":"181.58684ms","start":"2026-04-10T14:01:54.018069Z","end":"2026-04-10T14:01:54.199656Z","steps":["trace[920243662] 'agreement among raft nodes before linearized reading' (duration: 181.297662ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:54.215878Z","caller":"traceutil/trace.go:171","msg":"trace[1067210442] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"178.385965ms","start":"2026-04-10T14:01:54.037468Z","end":"2026-04-10T14:01:54.215854Z","steps":["trace[1067210442] 'process raft request' (duration: 178.20232ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:01:54.216071Z","caller":"traceutil/trace.go:171","msg":"trace[362620426] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"177.672405ms","start":"2026-04-10T14:01:54.038358Z","end":"2026-04-10T14:01:54.21603Z","steps":["trace[362620426] 'process raft request' (duration: 177.455739ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T14:02:10.002749Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e5.wal"} {"level":"info","ts":"2026-04-10T14:03:34.094281Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1918dd9317ae83a2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-10T14:03:34.100608Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-10T14:03:34.10075Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-10T14:05:50.586092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8162} {"level":"info","ts":"2026-04-10T14:05:50.651344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8162,"took":"62.36245ms","hash":366603046,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":28196864,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-10T14:05:50.651427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":366603046,"revision":8162,"compact-revision":6076} {"level":"info","ts":"2026-04-10T14:10:50.593888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10522} {"level":"info","ts":"2026-04-10T14:10:50.677224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10522,"took":"80.399423ms","hash":4221857899,"current-db-size-bytes":58183680,"current-db-size":"58 MB","current-db-size-in-use-bytes":21667840,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-10T14:10:50.677306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4221857899,"revision":10522,"compact-revision":8162}