{"level":"warn","ts":"2026-03-05T09:46:39.545713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T09:46:39.545817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243: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.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-05T09:46:39.546764Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T09:46:39.546784Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-03-05T09:46:39.546822Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-05T09:46:39.547941Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-03-05T09:46:39.548027Z","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.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-05T09:46:39.551999Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.757979ms"} {"level":"info","ts":"2026-03-05T09:46:39.562965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-03-05T09:46:39.563074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T09:46:39.56311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-03-05T09:46:39.563129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T09:46:39.56314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-03-05T09:46:39.563202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-03-05T09:46:39.570976Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T09:46:39.572562Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T09:46:39.574433Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-05T09:46:39.577821Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T09:46:39.578037Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T09:46:39.578283Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-05T09:46:39.578461Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-05T09:46:39.578576Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-05T09:46:39.579038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-03-05T09:46:39.579365Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-03-05T09:46:39.583876Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-05T09:46:39.583999Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-03-05T09:46:39.584057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-03-05T09:46:39.584325Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T09:46:39.584378Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T09:46:40.564037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T09:46:40.564143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T09:46:40.564179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-03-05T09:46:40.564199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-03-05T09:46:40.564215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-03-05T09:46:40.564232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-03-05T09:46:40.564254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-03-05T09:46:40.566406Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T09:46:40.566414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T09:46:40.566498Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:46:40.566761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T09:46:40.56684Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T09:46:40.566915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T09:46:40.567678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:46:40.567801Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:46:40.567842Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T09:46:40.570343Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T09:46:40.570393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-03-05T09:46:57.697909Z","caller":"traceutil/trace.go:171","msg":"trace[1989084225] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"262.102639ms","start":"2026-03-05T09:46:57.435767Z","end":"2026-03-05T09:46:57.697869Z","steps":["trace[1989084225] 'process raft request' (duration: 181.805217ms)","trace[1989084225] 'compare' (duration: 79.78437ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:47:08.679549Z","caller":"traceutil/trace.go:171","msg":"trace[1729640056] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"234.528283ms","start":"2026-03-05T09:47:08.444996Z","end":"2026-03-05T09:47:08.679524Z","steps":["trace[1729640056] 'process raft request' (duration: 227.858685ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:15.895137Z","caller":"traceutil/trace.go:171","msg":"trace[1080758508] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"211.850787ms","start":"2026-03-05T09:47:15.68326Z","end":"2026-03-05T09:47:15.895111Z","steps":["trace[1080758508] 'process raft request' (duration: 211.652443ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:16.050363Z","caller":"traceutil/trace.go:171","msg":"trace[2117858960] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:559; }","duration":"256.597515ms","start":"2026-03-05T09:47:15.793738Z","end":"2026-03-05T09:47:16.050336Z","steps":["trace[2117858960] 'read index received' (duration: 101.2981ms)","trace[2117858960] 'applied index is now lower than readState.Index' (duration: 155.298495ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:47:16.050438Z","caller":"traceutil/trace.go:171","msg":"trace[145331201] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"316.532043ms","start":"2026-03-05T09:47:15.733871Z","end":"2026-03-05T09:47:16.050403Z","steps":["trace[145331201] 'process raft request' (duration: 316.304968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:47:16.050657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.803551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T09:47:16.050705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.858871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-05T09:47:16.051485Z","caller":"traceutil/trace.go:171","msg":"trace[2138080634] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:548; }","duration":"132.706072ms","start":"2026-03-05T09:47:15.918764Z","end":"2026-03-05T09:47:16.05147Z","steps":["trace[2138080634] 'agreement among raft nodes before linearized reading' (duration: 131.729209ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:16.051493Z","caller":"traceutil/trace.go:171","msg":"trace[1534852320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"257.796503ms","start":"2026-03-05T09:47:15.793682Z","end":"2026-03-05T09:47:16.051479Z","steps":["trace[1534852320] 'agreement among raft nodes before linearized reading' (duration: 256.79985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:47:16.051862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:47:15.733843Z","time spent":"316.681666ms","remote":"127.0.0.1:43838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:47:16.259528Z","caller":"traceutil/trace.go:171","msg":"trace[1323614048] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"200.32169ms","start":"2026-03-05T09:47:16.059169Z","end":"2026-03-05T09:47:16.259491Z","steps":["trace[1323614048] 'read index received' (duration: 129.448408ms)","trace[1323614048] 'applied index is now lower than readState.Index' (duration: 70.871562ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:47:16.25968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.493725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T09:47:16.259719Z","caller":"traceutil/trace.go:171","msg":"trace[962339209] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:549; }","duration":"200.609537ms","start":"2026-03-05T09:47:16.059097Z","end":"2026-03-05T09:47:16.259707Z","steps":["trace[962339209] 'agreement among raft nodes before linearized reading' (duration: 200.504625ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:16.259946Z","caller":"traceutil/trace.go:171","msg":"trace[583597536] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"203.655805ms","start":"2026-03-05T09:47:16.055941Z","end":"2026-03-05T09:47:16.259597Z","steps":["trace[583597536] 'process raft request' (duration: 132.719592ms)","trace[583597536] 'compare' (duration: 70.641086ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:47:16.71497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.356955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:47:16.715076Z","caller":"traceutil/trace.go:171","msg":"trace[350149298] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"287.27014ms","start":"2026-03-05T09:47:16.427787Z","end":"2026-03-05T09:47:16.715057Z","steps":["trace[350149298] 'read index received' (duration: 54.452µs)","trace[350149298] 'applied index is now lower than readState.Index' (duration: 287.214458ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:47:16.71511Z","caller":"traceutil/trace.go:171","msg":"trace[2080130877] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"356.582167ms","start":"2026-03-05T09:47:16.358509Z","end":"2026-03-05T09:47:16.715092Z","steps":["trace[2080130877] 'process raft request' (duration: 45.863744ms)","trace[2080130877] 'compare' (duration: 310.222312ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:47:16.715159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:47:16.358488Z","time spent":"356.647018ms","remote":"127.0.0.1:43804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":365,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T09:47:16.715337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.162487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r56zd\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-05T09:47:16.715362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.549617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-05T09:47:16.715401Z","caller":"traceutil/trace.go:171","msg":"trace[1891917573] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r56zd; range_end:; response_count:1; response_revision:550; }","duration":"282.281769ms","start":"2026-03-05T09:47:16.433101Z","end":"2026-03-05T09:47:16.715383Z","steps":["trace[1891917573] 'agreement among raft nodes before linearized reading' (duration: 282.079704ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:16.715478Z","caller":"traceutil/trace.go:171","msg":"trace[160533266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"287.71516ms","start":"2026-03-05T09:47:16.427705Z","end":"2026-03-05T09:47:16.71542Z","steps":["trace[160533266] 'agreement among raft nodes before linearized reading' (duration: 287.399772ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:17.03342Z","caller":"traceutil/trace.go:171","msg":"trace[1111874500] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"314.713562ms","start":"2026-03-05T09:47:16.718679Z","end":"2026-03-05T09:47:17.033393Z","steps":["trace[1111874500] 'process raft request' (duration: 314.397425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:47:17.033524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:47:16.718615Z","time spent":"314.858735ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1733,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T09:47:17.033501Z","caller":"traceutil/trace.go:171","msg":"trace[1228264639] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"314.208551ms","start":"2026-03-05T09:47:16.719268Z","end":"2026-03-05T09:47:17.033476Z","steps":["trace[1228264639] 'process raft request' (duration: 314.113139ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:17.033516Z","caller":"traceutil/trace.go:171","msg":"trace[2036021357] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"314.252442ms","start":"2026-03-05T09:47:16.719238Z","end":"2026-03-05T09:47:17.03349Z","steps":["trace[2036021357] 'process raft request' (duration: 314.048747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:47:17.033648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:47:16.719254Z","time spent":"314.316943ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:47:17.033685Z","caller":"traceutil/trace.go:171","msg":"trace[1214259907] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"301.335014ms","start":"2026-03-05T09:47:16.732334Z","end":"2026-03-05T09:47:17.033669Z","steps":["trace[1214259907] 'process raft request' (duration: 301.099638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:47:17.033651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:47:16.719224Z","time spent":"314.356155ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:47:17.033782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:47:16.73231Z","time spent":"301.417685ms","remote":"127.0.0.1:43840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:47:17.096717Z","caller":"traceutil/trace.go:171","msg":"trace[881800393] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"286.512983ms","start":"2026-03-05T09:47:16.810173Z","end":"2026-03-05T09:47:17.096686Z","steps":["trace[881800393] 'process raft request' (duration: 281.267416ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:18.689104Z","caller":"traceutil/trace.go:171","msg":"trace[838746049] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"106.833454ms","start":"2026-03-05T09:47:18.582237Z","end":"2026-03-05T09:47:18.689071Z","steps":["trace[838746049] 'process raft request' (duration: 106.627949ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:47:35.964269Z","caller":"traceutil/trace.go:171","msg":"trace[663389563] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"251.974003ms","start":"2026-03-05T09:47:35.712267Z","end":"2026-03-05T09:47:35.964241Z","steps":["trace[663389563] 'process raft request' (duration: 251.807589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:48:32.531343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.823951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:48:32.531474Z","caller":"traceutil/trace.go:171","msg":"trace[277136420] linearizableReadLoop","detail":"{readStateIndex:1665; appliedIndex:1664; }","duration":"289.856166ms","start":"2026-03-05T09:48:32.241601Z","end":"2026-03-05T09:48:32.531457Z","steps":["trace[277136420] 'read index received' (duration: 15.702727ms)","trace[277136420] 'applied index is now lower than readState.Index' (duration: 274.151969ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:48:32.531613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.024641ms","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-03-05T09:48:32.531647Z","caller":"traceutil/trace.go:171","msg":"trace[336510566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1606; }","duration":"290.065042ms","start":"2026-03-05T09:48:32.241572Z","end":"2026-03-05T09:48:32.531637Z","steps":["trace[336510566] 'agreement among raft nodes before linearized reading' (duration: 289.932418ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:48:32.531648Z","caller":"traceutil/trace.go:171","msg":"trace[10543464] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"332.544893ms","start":"2026-03-05T09:48:32.19908Z","end":"2026-03-05T09:48:32.531625Z","steps":["trace[10543464] 'process raft request' (duration: 58.291522ms)","trace[10543464] 'compare' (duration: 273.698569ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:48:32.531791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:48:32.199049Z","time spent":"332.682387ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:48:33.019118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.924701ms","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-03-05T09:48:33.019197Z","caller":"traceutil/trace.go:171","msg":"trace[865689103] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1609; }","duration":"150.081424ms","start":"2026-03-05T09:48:32.869096Z","end":"2026-03-05T09:48:33.019177Z","steps":["trace[865689103] 'range keys from in-memory index tree' (duration: 149.712506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:48:33.019202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.807942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-483d9220-3e7e-4dd6-97b5-887b7af01a6a\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-05T09:48:33.019183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.548336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T09:48:33.019275Z","caller":"traceutil/trace.go:171","msg":"trace[1614143661] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-483d9220-3e7e-4dd6-97b5-887b7af01a6a; range_end:; response_count:1; response_revision:1609; }","duration":"172.946975ms","start":"2026-03-05T09:48:32.846312Z","end":"2026-03-05T09:48:33.019258Z","steps":["trace[1614143661] 'range keys from in-memory index tree' (duration: 172.555987ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:48:33.019283Z","caller":"traceutil/trace.go:171","msg":"trace[26640199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1609; }","duration":"111.706679ms","start":"2026-03-05T09:48:32.90756Z","end":"2026-03-05T09:48:33.019267Z","steps":["trace[26640199] 'count revisions from in-memory index tree' (duration: 111.466945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:49:11.164993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.627509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.1899e906a2c8e476\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-03-05T09:49:11.165043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.891054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-05T09:49:11.165102Z","caller":"traceutil/trace.go:171","msg":"trace[245542434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1856; }","duration":"297.989576ms","start":"2026-03-05T09:49:10.867098Z","end":"2026-03-05T09:49:11.165088Z","steps":["trace[245542434] 'range keys from in-memory index tree' (duration: 297.755781ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:49:11.165097Z","caller":"traceutil/trace.go:171","msg":"trace[475890624] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.1899e906a2c8e476; range_end:; response_count:1; response_revision:1856; }","duration":"277.734901ms","start":"2026-03-05T09:49:10.887331Z","end":"2026-03-05T09:49:11.165065Z","steps":["trace[475890624] 'range keys from in-memory index tree' (duration: 277.394613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:49:16.50829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.787046ms","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-03-05T09:49:16.508426Z","caller":"traceutil/trace.go:171","msg":"trace[832039688] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1884; }","duration":"184.96554ms","start":"2026-03-05T09:49:16.323442Z","end":"2026-03-05T09:49:16.508408Z","steps":["trace[832039688] 'range keys from in-memory index tree' (duration: 184.612152ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:49:28.158188Z","caller":"traceutil/trace.go:171","msg":"trace[555310581] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"233.761157ms","start":"2026-03-05T09:49:27.924383Z","end":"2026-03-05T09:49:28.158144Z","steps":["trace[555310581] 'process raft request' (duration: 218.152492ms)","trace[555310581] 'compare' (duration: 15.015492ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:49:28.434304Z","caller":"traceutil/trace.go:171","msg":"trace[257914766] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"220.151106ms","start":"2026-03-05T09:49:28.214124Z","end":"2026-03-05T09:49:28.434275Z","steps":["trace[257914766] 'process raft request' (duration: 125.347021ms)","trace[257914766] 'compare' (duration: 94.699723ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:49:28.949339Z","caller":"traceutil/trace.go:171","msg":"trace[1108712383] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"213.311459ms","start":"2026-03-05T09:49:28.736004Z","end":"2026-03-05T09:49:28.949316Z","steps":["trace[1108712383] 'process raft request' (duration: 213.189066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:49:29.344718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.290305ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-05T09:49:29.345346Z","caller":"traceutil/trace.go:171","msg":"trace[1647754253] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"527.080653ms","start":"2026-03-05T09:49:28.818235Z","end":"2026-03-05T09:49:29.345316Z","steps":["trace[1647754253] 'process raft request' (duration: 526.91176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:49:29.345495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:49:28.818203Z","time spent":"527.210976ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T09:49:29.34584Z","caller":"traceutil/trace.go:171","msg":"trace[332125951] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2012; }","duration":"506.283646ms","start":"2026-03-05T09:49:28.839538Z","end":"2026-03-05T09:49:29.345822Z","steps":["trace[332125951] 'read index received' (duration: 109.691554ms)","trace[332125951] 'applied index is now lower than readState.Index' (duration: 396.590292ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:49:29.346035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.488551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T09:49:29.346103Z","caller":"traceutil/trace.go:171","msg":"trace[502815305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1943; }","duration":"506.592602ms","start":"2026-03-05T09:49:28.839492Z","end":"2026-03-05T09:49:29.346085Z","steps":["trace[502815305] 'agreement among raft nodes before linearized reading' (duration: 506.431769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:49:29.346132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:49:28.839476Z","time spent":"506.649914ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-05T09:49:34.621609Z","caller":"traceutil/trace.go:171","msg":"trace[309728003] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"169.255274ms","start":"2026-03-05T09:49:34.45233Z","end":"2026-03-05T09:49:34.621586Z","steps":["trace[309728003] 'process raft request' (duration: 169.102841ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:50:27.726983Z","caller":"traceutil/trace.go:171","msg":"trace[1754068978] linearizableReadLoop","detail":"{readStateIndex:2486; appliedIndex:2485; }","duration":"112.730142ms","start":"2026-03-05T09:50:27.614225Z","end":"2026-03-05T09:50:27.726955Z","steps":["trace[1754068978] 'read index received' (duration: 112.579799ms)","trace[1754068978] 'applied index is now lower than readState.Index' (duration: 149.283µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:50:27.727077Z","caller":"traceutil/trace.go:171","msg":"trace[1102602593] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"175.822565ms","start":"2026-03-05T09:50:27.551194Z","end":"2026-03-05T09:50:27.727017Z","steps":["trace[1102602593] 'process raft request' (duration: 175.557979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:50:27.727283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.039699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T09:50:27.72734Z","caller":"traceutil/trace.go:171","msg":"trace[1610261903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2396; }","duration":"113.195472ms","start":"2026-03-05T09:50:27.614131Z","end":"2026-03-05T09:50:27.727327Z","steps":["trace[1610261903] 'agreement among raft nodes before linearized reading' (duration: 112.983987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:50:27.727515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.750802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T09:50:27.727634Z","caller":"traceutil/trace.go:171","msg":"trace[547508589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2396; }","duration":"112.895464ms","start":"2026-03-05T09:50:27.614699Z","end":"2026-03-05T09:50:27.727595Z","steps":["trace[547508589] 'agreement among raft nodes before linearized reading' (duration: 112.618908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:50:27.727957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.625421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-05T09:50:27.728033Z","caller":"traceutil/trace.go:171","msg":"trace[671272908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2396; }","duration":"113.759294ms","start":"2026-03-05T09:50:27.614252Z","end":"2026-03-05T09:50:27.728011Z","steps":["trace[671272908] 'agreement among raft nodes before linearized reading' (duration: 112.963527ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:51:01.028557Z","caller":"traceutil/trace.go:171","msg":"trace[52033363] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"151.376474ms","start":"2026-03-05T09:51:00.877116Z","end":"2026-03-05T09:51:01.028492Z","steps":["trace[52033363] 'process raft request' (duration: 151.255142ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:51:01.044098Z","caller":"traceutil/trace.go:171","msg":"trace[1565948905] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"103.772721ms","start":"2026-03-05T09:51:00.940308Z","end":"2026-03-05T09:51:01.04408Z","steps":["trace[1565948905] 'process raft request' (duration: 103.684629ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:51:01.554394Z","caller":"traceutil/trace.go:171","msg":"trace[1076618987] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"303.937034ms","start":"2026-03-05T09:51:01.250428Z","end":"2026-03-05T09:51:01.554365Z","steps":["trace[1076618987] 'process raft request' (duration: 303.782551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:51:01.554996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T09:51:01.250404Z","time spent":"304.283231ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T09:55:05.163714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.77487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T09:55:05.163801Z","caller":"traceutil/trace.go:171","msg":"trace[1657560462] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4682; }","duration":"190.922983ms","start":"2026-03-05T09:55:04.972865Z","end":"2026-03-05T09:55:05.163788Z","steps":["trace[1657560462] 'count revisions from in-memory index tree' (duration: 190.695548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:05.163962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.711665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-bm6cw-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-03-05T09:55:05.163989Z","caller":"traceutil/trace.go:171","msg":"trace[1116414902] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-bm6cw-tls; range_end:; response_count:1; response_revision:4682; }","duration":"142.770306ms","start":"2026-03-05T09:55:05.021211Z","end":"2026-03-05T09:55:05.163981Z","steps":["trace[1116414902] 'range keys from in-memory index tree' (duration: 142.622263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:06.648696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.257059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:55:06.649118Z","caller":"traceutil/trace.go:171","msg":"trace[1206950928] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"174.169905ms","start":"2026-03-05T09:55:06.474927Z","end":"2026-03-05T09:55:06.649097Z","steps":["trace[1206950928] 'process raft request' (duration: 66.411776ms)","trace[1206950928] 'compare' (duration: 107.133956ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:55:17.28957Z","caller":"traceutil/trace.go:171","msg":"trace[907617583] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"298.982118ms","start":"2026-03-05T09:55:16.990543Z","end":"2026-03-05T09:55:17.289525Z","steps":["trace[907617583] 'process raft request' (duration: 298.776704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:25.502155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.083963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-05T09:55:25.50227Z","caller":"traceutil/trace.go:171","msg":"trace[541597761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5113; }","duration":"166.255667ms","start":"2026-03-05T09:55:25.335992Z","end":"2026-03-05T09:55:25.502248Z","steps":["trace[541597761] 'range keys from in-memory index tree' (duration: 165.901449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:25.502365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.946944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-05T09:55:25.50241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.569048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rrt9r\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-05T09:55:25.502455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.697372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-05T09:55:25.502473Z","caller":"traceutil/trace.go:171","msg":"trace[1107700015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5113; }","duration":"147.127959ms","start":"2026-03-05T09:55:25.355321Z","end":"2026-03-05T09:55:25.502449Z","steps":["trace[1107700015] 'range keys from in-memory index tree' (duration: 146.73165ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:55:25.50257Z","caller":"traceutil/trace.go:171","msg":"trace[1025715184] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rrt9r; range_end:; response_count:1; response_revision:5113; }","duration":"139.767352ms","start":"2026-03-05T09:55:25.362759Z","end":"2026-03-05T09:55:25.502526Z","steps":["trace[1025715184] 'range keys from in-memory index tree' (duration: 139.331733ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:55:25.502579Z","caller":"traceutil/trace.go:171","msg":"trace[589274546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5113; }","duration":"140.879666ms","start":"2026-03-05T09:55:25.361677Z","end":"2026-03-05T09:55:25.502557Z","steps":["trace[589274546] 'range keys from in-memory index tree' (duration: 140.429246ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:55:25.573885Z","caller":"traceutil/trace.go:171","msg":"trace[101400698] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"196.732867ms","start":"2026-03-05T09:55:25.377115Z","end":"2026-03-05T09:55:25.573848Z","steps":["trace[101400698] 'process raft request' (duration: 196.544893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:25.734964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.284233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T09:55:25.735198Z","caller":"traceutil/trace.go:171","msg":"trace[762073350] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"157.220834ms","start":"2026-03-05T09:55:25.577963Z","end":"2026-03-05T09:55:25.735184Z","steps":["trace[762073350] 'process raft request' (duration: 157.179353ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:55:25.735318Z","caller":"traceutil/trace.go:171","msg":"trace[691985570] linearizableReadLoop","detail":"{readStateIndex:5305; appliedIndex:5302; }","duration":"161.499846ms","start":"2026-03-05T09:55:25.573811Z","end":"2026-03-05T09:55:25.735311Z","steps":["trace[691985570] 'read index received' (duration: 58.787044ms)","trace[691985570] 'applied index is now lower than readState.Index' (duration: 102.712162ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T09:55:25.73535Z","caller":"traceutil/trace.go:171","msg":"trace[1790108140] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"228.753121ms","start":"2026-03-05T09:55:25.506538Z","end":"2026-03-05T09:55:25.735291Z","steps":["trace[1790108140] 'process raft request' (duration: 228.523166ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:55:25.735398Z","caller":"traceutil/trace.go:171","msg":"trace[680197739] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"228.360361ms","start":"2026-03-05T09:55:25.507002Z","end":"2026-03-05T09:55:25.735362Z","steps":["trace[680197739] 'process raft request' (duration: 228.100606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:25.735465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.120328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7f21063-a288-4b19-809e-67dfb368a4fd\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-05T09:55:25.735489Z","caller":"traceutil/trace.go:171","msg":"trace[2111351163] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7f21063-a288-4b19-809e-67dfb368a4fd; range_end:; response_count:1; response_revision:5118; }","duration":"177.17434ms","start":"2026-03-05T09:55:25.558307Z","end":"2026-03-05T09:55:25.735481Z","steps":["trace[2111351163] 'agreement among raft nodes before linearized reading' (duration: 177.062918ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:55:25.735533Z","caller":"traceutil/trace.go:171","msg":"trace[1633580931] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"229.19036ms","start":"2026-03-05T09:55:25.506328Z","end":"2026-03-05T09:55:25.735518Z","steps":["trace[1633580931] 'process raft request' (duration: 126.274473ms)","trace[1633580931] 'compare' (duration: 102.175721ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T09:55:25.735536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.147956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-05T09:55:25.73561Z","caller":"traceutil/trace.go:171","msg":"trace[1794194091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5118; }","duration":"137.267438ms","start":"2026-03-05T09:55:25.598327Z","end":"2026-03-05T09:55:25.735594Z","steps":["trace[1794194091] 'agreement among raft nodes before linearized reading' (duration: 137.075524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T09:55:25.735726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.271519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80215"} {"level":"info","ts":"2026-03-05T09:55:25.735816Z","caller":"traceutil/trace.go:171","msg":"trace[424462230] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5118; }","duration":"228.414333ms","start":"2026-03-05T09:55:25.507383Z","end":"2026-03-05T09:55:25.735798Z","steps":["trace[424462230] 'agreement among raft nodes before linearized reading' (duration: 228.117056ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T09:56:40.595886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2782} {"level":"info","ts":"2026-03-05T09:56:40.658226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2782,"took":"60.718134ms","hash":30893059,"current-db-size-bytes":41398272,"current-db-size":"41 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-05T09:56:40.658342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":30893059,"revision":2782,"compact-revision":-1} {"level":"info","ts":"2026-03-05T10:01:40.68381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5770} {"level":"info","ts":"2026-03-05T10:01:40.816837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5770,"took":"130.224579ms","hash":1196095764,"current-db-size-bytes":43503616,"current-db-size":"44 MB","current-db-size-in-use-bytes":23887872,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-05T10:01:40.816937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1196095764,"revision":5770,"compact-revision":2782} {"level":"info","ts":"2026-03-05T10:01:43.047276Z","caller":"traceutil/trace.go:171","msg":"trace[1500110484] transaction","detail":"{read_only:false; response_revision:7822; number_of_response:1; }","duration":"286.850263ms","start":"2026-03-05T10:01:42.760379Z","end":"2026-03-05T10:01:43.047229Z","steps":["trace[1500110484] 'process raft request' (duration: 286.564056ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:01:53.728688Z","caller":"traceutil/trace.go:171","msg":"trace[282108391] transaction","detail":"{read_only:false; response_revision:7873; number_of_response:1; }","duration":"123.009464ms","start":"2026-03-05T10:01:53.605645Z","end":"2026-03-05T10:01:53.728654Z","steps":["trace[282108391] 'process raft request' (duration: 110.224412ms)","trace[282108391] 'compare' (duration: 12.577318ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T10:01:54.539159Z","caller":"traceutil/trace.go:171","msg":"trace[29903827] transaction","detail":"{read_only:false; response_revision:7880; number_of_response:1; }","duration":"120.076113ms","start":"2026-03-05T10:01:54.419055Z","end":"2026-03-05T10:01:54.539131Z","steps":["trace[29903827] 'process raft request' (duration: 119.857738ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:01:55.898162Z","caller":"traceutil/trace.go:171","msg":"trace[285935283] linearizableReadLoop","detail":"{readStateIndex:8168; appliedIndex:8167; }","duration":"129.738759ms","start":"2026-03-05T10:01:55.768401Z","end":"2026-03-05T10:01:55.89814Z","steps":["trace[285935283] 'read index received' (duration: 129.472843ms)","trace[285935283] 'applied index is now lower than readState.Index' (duration: 264.896µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T10:01:55.898402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.982554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-05T10:01:55.898486Z","caller":"traceutil/trace.go:171","msg":"trace[49320765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7882; }","duration":"130.103137ms","start":"2026-03-05T10:01:55.768371Z","end":"2026-03-05T10:01:55.898474Z","steps":["trace[49320765] 'agreement among raft nodes before linearized reading' (duration: 129.916733ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:01:55.898339Z","caller":"traceutil/trace.go:171","msg":"trace[25711868] transaction","detail":"{read_only:false; response_revision:7882; number_of_response:1; }","duration":"136.80109ms","start":"2026-03-05T10:01:55.761456Z","end":"2026-03-05T10:01:55.898257Z","steps":["trace[25711868] 'process raft request' (duration: 136.525794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:01:55.898556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.792314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-05T10:01:55.898616Z","caller":"traceutil/trace.go:171","msg":"trace[1910828791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7882; }","duration":"124.865605ms","start":"2026-03-05T10:01:55.773737Z","end":"2026-03-05T10:01:55.898603Z","steps":["trace[1910828791] 'agreement among raft nodes before linearized reading' (duration: 124.710772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:01:55.8986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-05T10:01:55.898624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.818313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-05T10:01:55.898672Z","caller":"traceutil/trace.go:171","msg":"trace[1149044507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7882; }","duration":"125.044569ms","start":"2026-03-05T10:01:55.773609Z","end":"2026-03-05T10:01:55.898654Z","steps":["trace[1149044507] 'agreement among raft nodes before linearized reading' (duration: 124.790914ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:01:55.898703Z","caller":"traceutil/trace.go:171","msg":"trace[273603422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7882; }","duration":"124.929897ms","start":"2026-03-05T10:01:55.773752Z","end":"2026-03-05T10:01:55.898682Z","steps":["trace[273603422] 'agreement among raft nodes before linearized reading' (duration: 124.683062ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:02:23.676816Z","caller":"traceutil/trace.go:171","msg":"trace[284474608] transaction","detail":"{read_only:false; response_revision:8072; number_of_response:1; }","duration":"193.08655ms","start":"2026-03-05T10:02:23.483704Z","end":"2026-03-05T10:02:23.67679Z","steps":["trace[284474608] 'process raft request' (duration: 192.763433ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:02:23.897177Z","caller":"traceutil/trace.go:171","msg":"trace[996886223] linearizableReadLoop","detail":"{readStateIndex:8366; appliedIndex:8365; }","duration":"218.212522ms","start":"2026-03-05T10:02:23.678922Z","end":"2026-03-05T10:02:23.897134Z","steps":["trace[996886223] 'read index received' (duration: 190.870622ms)","trace[996886223] 'applied index is now lower than readState.Index' (duration: 27.34112ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T10:02:23.897282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.346684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T10:02:23.897309Z","caller":"traceutil/trace.go:171","msg":"trace[1089713132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8073; }","duration":"218.410436ms","start":"2026-03-05T10:02:23.678891Z","end":"2026-03-05T10:02:23.897302Z","steps":["trace[1089713132] 'agreement among raft nodes before linearized reading' (duration: 218.335795ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:02:26.901Z","caller":"traceutil/trace.go:171","msg":"trace[478537758] linearizableReadLoop","detail":"{readStateIndex:8384; appliedIndex:8383; }","duration":"113.226407ms","start":"2026-03-05T10:02:26.787744Z","end":"2026-03-05T10:02:26.900971Z","steps":["trace[478537758] 'read index received' (duration: 113.002693ms)","trace[478537758] 'applied index is now lower than readState.Index' (duration: 222.404µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T10:02:26.901204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.443682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T10:02:26.901251Z","caller":"traceutil/trace.go:171","msg":"trace[1330617324] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8090; }","duration":"113.549125ms","start":"2026-03-05T10:02:26.787687Z","end":"2026-03-05T10:02:26.901236Z","steps":["trace[1330617324] 'agreement among raft nodes before linearized reading' (duration: 113.428813ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:02:26.901323Z","caller":"traceutil/trace.go:171","msg":"trace[1649048184] transaction","detail":"{read_only:false; response_revision:8090; number_of_response:1; }","duration":"163.821839ms","start":"2026-03-05T10:02:26.73739Z","end":"2026-03-05T10:02:26.901212Z","steps":["trace[1649048184] 'process raft request' (duration: 163.41836ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:02:36.809629Z","caller":"traceutil/trace.go:171","msg":"trace[1501871941] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"129.568784ms","start":"2026-03-05T10:02:36.680044Z","end":"2026-03-05T10:02:36.809613Z","steps":["trace[1501871941] 'process raft request' (duration: 129.265287ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:05:45.644568Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"864e14457b828f9c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-05T10:05:45.649017Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-05T10:05:45.649106Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-05T10:06:40.690634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7813} {"level":"info","ts":"2026-03-05T10:06:40.750768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7813,"took":"58.410399ms","hash":1386191733,"current-db-size-bytes":43503616,"current-db-size":"44 MB","current-db-size-in-use-bytes":26886144,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-05T10:06:40.750828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1386191733,"revision":7813,"compact-revision":5770} {"level":"info","ts":"2026-03-05T10:11:40.702875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10149} {"level":"info","ts":"2026-03-05T10:11:40.794559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10149,"took":"87.605404ms","hash":2690514291,"current-db-size-bytes":43503616,"current-db-size":"44 MB","current-db-size-in-use-bytes":24043520,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-05T10:11:40.794653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2690514291,"revision":10149,"compact-revision":7813} {"level":"info","ts":"2026-03-05T10:13:03.641693Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000334e.wal"} {"level":"info","ts":"2026-03-05T10:13:59.097731Z","caller":"traceutil/trace.go:171","msg":"trace[999303868] transaction","detail":"{read_only:false; response_revision:13009; number_of_response:1; }","duration":"116.905971ms","start":"2026-03-05T10:13:58.98079Z","end":"2026-03-05T10:13:59.097696Z","steps":["trace[999303868] 'process raft request' (duration: 116.712257ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:14:52.46131Z","caller":"traceutil/trace.go:171","msg":"trace[911603176] linearizableReadLoop","detail":"{readStateIndex:13759; appliedIndex:13758; }","duration":"137.408796ms","start":"2026-03-05T10:14:52.323878Z","end":"2026-03-05T10:14:52.461287Z","steps":["trace[911603176] 'read index received' (duration: 137.233792ms)","trace[911603176] 'applied index is now lower than readState.Index' (duration: 173.814µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T10:14:52.461493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.61247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-05T10:14:52.461421Z","caller":"traceutil/trace.go:171","msg":"trace[301236539] transaction","detail":"{read_only:false; response_revision:13282; number_of_response:1; }","duration":"162.803732ms","start":"2026-03-05T10:14:52.298563Z","end":"2026-03-05T10:14:52.461367Z","steps":["trace[301236539] 'process raft request' (duration: 162.526147ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:14:52.461538Z","caller":"traceutil/trace.go:171","msg":"trace[1456036823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13282; }","duration":"137.722182ms","start":"2026-03-05T10:14:52.323798Z","end":"2026-03-05T10:14:52.461521Z","steps":["trace[1456036823] 'agreement among raft nodes before linearized reading' (duration: 137.604369ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:14:52.65539Z","caller":"traceutil/trace.go:171","msg":"trace[60833211] transaction","detail":"{read_only:false; response_revision:13283; number_of_response:1; }","duration":"256.912933ms","start":"2026-03-05T10:14:52.398421Z","end":"2026-03-05T10:14:52.655334Z","steps":["trace[60833211] 'process raft request' (duration: 237.976302ms)","trace[60833211] 'compare' (duration: 18.718786ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T10:14:52.655428Z","caller":"traceutil/trace.go:171","msg":"trace[1658385992] transaction","detail":"{read_only:false; response_revision:13284; number_of_response:1; }","duration":"177.820741ms","start":"2026-03-05T10:14:52.477567Z","end":"2026-03-05T10:14:52.655388Z","steps":["trace[1658385992] 'process raft request' (duration: 177.752499ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:14:52.655448Z","caller":"traceutil/trace.go:171","msg":"trace[1261563058] linearizableReadLoop","detail":"{readStateIndex:13760; appliedIndex:13759; }","duration":"194.010993ms","start":"2026-03-05T10:14:52.461384Z","end":"2026-03-05T10:14:52.655395Z","steps":["trace[1261563058] 'read index received' (duration: 175.054832ms)","trace[1261563058] 'applied index is now lower than readState.Index' (duration: 18.953551ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T10:14:52.655649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.86585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T10:14:52.65584Z","caller":"traceutil/trace.go:171","msg":"trace[276354216] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:13284; }","duration":"281.116125ms","start":"2026-03-05T10:14:52.374693Z","end":"2026-03-05T10:14:52.655809Z","steps":["trace[276354216] 'agreement among raft nodes before linearized reading' (duration: 280.763767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:14:52.655902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.523142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-05T10:14:52.655928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.856861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T10:14:52.655958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.659686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T10:14:52.656043Z","caller":"traceutil/trace.go:171","msg":"trace[1845609259] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:13284; }","duration":"168.813129ms","start":"2026-03-05T10:14:52.487209Z","end":"2026-03-05T10:14:52.656022Z","steps":["trace[1845609259] 'agreement among raft nodes before linearized reading' (duration: 168.621595ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:14:52.656045Z","caller":"traceutil/trace.go:171","msg":"trace[1572002463] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:13284; }","duration":"256.048155ms","start":"2026-03-05T10:14:52.399968Z","end":"2026-03-05T10:14:52.656016Z","steps":["trace[1572002463] 'agreement among raft nodes before linearized reading' (duration: 255.854521ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:14:52.655976Z","caller":"traceutil/trace.go:171","msg":"trace[896455292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13284; }","duration":"324.654875ms","start":"2026-03-05T10:14:52.331301Z","end":"2026-03-05T10:14:52.655956Z","steps":["trace[896455292] 'agreement among raft nodes before linearized reading' (duration: 324.216026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:14:52.656164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T10:14:52.331283Z","time spent":"324.863639ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T10:14:52.656025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.547519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-05T10:14:52.656307Z","caller":"traceutil/trace.go:171","msg":"trace[72537948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13284; }","duration":"320.899265ms","start":"2026-03-05T10:14:52.335384Z","end":"2026-03-05T10:14:52.656283Z","steps":["trace[72537948] 'agreement among raft nodes before linearized reading' (duration: 320.192931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:14:52.656386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T10:14:52.335373Z","time spent":"320.968848ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T10:14:52.656382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.92189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-05T10:14:52.656508Z","caller":"traceutil/trace.go:171","msg":"trace[136693932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13284; }","duration":"325.123824ms","start":"2026-03-05T10:14:52.331354Z","end":"2026-03-05T10:14:52.656477Z","steps":["trace[136693932] 'agreement among raft nodes before linearized reading' (duration: 324.235476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:14:52.656561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T10:14:52.331325Z","time spent":"325.227137ms","remote":"127.0.0.1:43916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T10:14:52.656655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.337124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rrt9r\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-05T10:14:52.65674Z","caller":"traceutil/trace.go:171","msg":"trace[393953837] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rrt9r; range_end:; response_count:1; response_revision:13284; }","duration":"198.555398ms","start":"2026-03-05T10:14:52.458163Z","end":"2026-03-05T10:14:52.656719Z","steps":["trace[393953837] 'agreement among raft nodes before linearized reading' (duration: 197.627569ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:15:05.011573Z","caller":"traceutil/trace.go:171","msg":"trace[712949481] transaction","detail":"{read_only:false; response_revision:13363; number_of_response:1; }","duration":"119.954955ms","start":"2026-03-05T10:15:04.891586Z","end":"2026-03-05T10:15:05.011541Z","steps":["trace[712949481] 'process raft request' (duration: 119.794672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:15:12.05339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.886994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70a3dbbf-4c5a-4d25-bc80-69e96f921228\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-03-05T10:15:12.053492Z","caller":"traceutil/trace.go:171","msg":"trace[1144849148] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-70a3dbbf-4c5a-4d25-bc80-69e96f921228; range_end:; response_count:1; response_revision:13495; }","duration":"111.063668ms","start":"2026-03-05T10:15:11.942407Z","end":"2026-03-05T10:15:12.053471Z","steps":["trace[1144849148] 'range keys from in-memory index tree' (duration: 110.628469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:15:12.053594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.416933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-decc30ff-6170-4668-9e44-c364a643deef\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-03-05T10:15:12.053709Z","caller":"traceutil/trace.go:171","msg":"trace[1002268467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-decc30ff-6170-4668-9e44-c364a643deef; range_end:; response_count:1; response_revision:13495; }","duration":"109.586637ms","start":"2026-03-05T10:15:11.944094Z","end":"2026-03-05T10:15:12.053681Z","steps":["trace[1002268467] 'range keys from in-memory index tree' (duration: 109.140528ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:15:12.625099Z","caller":"traceutil/trace.go:171","msg":"trace[1664729886] linearizableReadLoop","detail":"{readStateIndex:13980; appliedIndex:13979; }","duration":"239.180037ms","start":"2026-03-05T10:15:12.385883Z","end":"2026-03-05T10:15:12.625063Z","steps":["trace[1664729886] 'read index received' (duration: 222.053145ms)","trace[1664729886] 'applied index is now lower than readState.Index' (duration: 17.125612ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T10:15:12.625346Z","caller":"traceutil/trace.go:171","msg":"trace[28177071] transaction","detail":"{read_only:false; response_revision:13498; number_of_response:1; }","duration":"454.49755ms","start":"2026-03-05T10:15:12.170808Z","end":"2026-03-05T10:15:12.625306Z","steps":["trace[28177071] 'process raft request' (duration: 437.151463ms)","trace[28177071] 'compare' (duration: 16.921338ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T10:15:12.625516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T10:15:12.170776Z","time spent":"454.619453ms","remote":"127.0.0.1:43840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3709,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T10:15:12.625515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.507344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-hb945.1899ea73b2cb20b0\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-03-05T10:15:12.625607Z","caller":"traceutil/trace.go:171","msg":"trace[1100447781] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-hb945.1899ea73b2cb20b0; range_end:; response_count:1; response_revision:13498; }","duration":"108.657957ms","start":"2026-03-05T10:15:12.516924Z","end":"2026-03-05T10:15:12.625582Z","steps":["trace[1100447781] 'agreement among raft nodes before linearized reading' (duration: 108.407602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:15:12.625779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.870081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T10:15:12.625881Z","caller":"traceutil/trace.go:171","msg":"trace[1365269374] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:13498; }","duration":"240.052166ms","start":"2026-03-05T10:15:12.385807Z","end":"2026-03-05T10:15:12.625859Z","steps":["trace[1365269374] 'agreement among raft nodes before linearized reading' (duration: 239.861182ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:16:40.709796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12077} {"level":"info","ts":"2026-03-05T10:16:40.787594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12077,"took":"74.916404ms","hash":3215659104,"current-db-size-bytes":43503616,"current-db-size":"44 MB","current-db-size-in-use-bytes":27398144,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-05T10:16:40.787674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3215659104,"revision":12077,"compact-revision":10149} {"level":"warn","ts":"2026-03-05T10:16:52.451709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.569513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T10:16:52.45181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.269487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T10:16:52.45186Z","caller":"traceutil/trace.go:171","msg":"trace[398616705] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14538; }","duration":"114.768036ms","start":"2026-03-05T10:16:52.33706Z","end":"2026-03-05T10:16:52.451828Z","steps":["trace[398616705] 'range keys from in-memory index tree' (duration: 114.42974ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:16:52.451931Z","caller":"traceutil/trace.go:171","msg":"trace[1246760461] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14538; }","duration":"114.44516ms","start":"2026-03-05T10:16:52.337454Z","end":"2026-03-05T10:16:52.451899Z","steps":["trace[1246760461] 'range keys from in-memory index tree' (duration: 114.186295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:52.451944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.973085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-03-05T10:16:52.452059Z","caller":"traceutil/trace.go:171","msg":"trace[1950745160] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14538; }","duration":"111.106249ms","start":"2026-03-05T10:16:52.340934Z","end":"2026-03-05T10:16:52.45204Z","steps":["trace[1950745160] 'range keys from in-memory index tree' (duration: 110.806893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:52.452076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.92744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T10:16:52.452332Z","caller":"traceutil/trace.go:171","msg":"trace[304619248] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14538; }","duration":"115.39439ms","start":"2026-03-05T10:16:52.33691Z","end":"2026-03-05T10:16:52.452305Z","steps":["trace[304619248] 'range keys from in-memory index tree' (duration: 114.751186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:53.49593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.072994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-05T10:16:53.496035Z","caller":"traceutil/trace.go:171","msg":"trace[598497855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14540; }","duration":"169.198707ms","start":"2026-03-05T10:16:53.32681Z","end":"2026-03-05T10:16:53.496009Z","steps":["trace[598497855] 'range keys from in-memory index tree' (duration: 168.931171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:53.49611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.33804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-05T10:16:53.496218Z","caller":"traceutil/trace.go:171","msg":"trace[2112181603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14540; }","duration":"169.527715ms","start":"2026-03-05T10:16:53.326666Z","end":"2026-03-05T10:16:53.496194Z","steps":["trace[2112181603] 'range keys from in-memory index tree' (duration: 169.038474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:53.496756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.33311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-03-05T10:16:53.496808Z","caller":"traceutil/trace.go:171","msg":"trace[866066713] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14540; }","duration":"178.438243ms","start":"2026-03-05T10:16:53.318357Z","end":"2026-03-05T10:16:53.496795Z","steps":["trace[866066713] 'range keys from in-memory index tree' (duration: 178.183537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:53.496825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.626089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-05T10:16:53.496857Z","caller":"traceutil/trace.go:171","msg":"trace[1673935266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14540; }","duration":"163.67201ms","start":"2026-03-05T10:16:53.333175Z","end":"2026-03-05T10:16:53.496847Z","steps":["trace[1673935266] 'range keys from in-memory index tree' (duration: 163.552818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:16:53.49676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.0469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-05T10:16:53.497009Z","caller":"traceutil/trace.go:171","msg":"trace[568228769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14540; }","duration":"165.307775ms","start":"2026-03-05T10:16:53.331682Z","end":"2026-03-05T10:16:53.496989Z","steps":["trace[568228769] 'range keys from in-memory index tree' (duration: 164.962558ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:21:40.724067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14482} {"level":"info","ts":"2026-03-05T10:21:40.823705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14482,"took":"96.445397ms","hash":918839490,"current-db-size-bytes":43503616,"current-db-size":"44 MB","current-db-size-in-use-bytes":28557312,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-05T10:21:40.823861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":918839490,"revision":14482,"compact-revision":12077} {"level":"info","ts":"2026-03-05T10:23:07.401071Z","caller":"traceutil/trace.go:171","msg":"trace[773620912] transaction","detail":"{read_only:false; response_revision:17008; number_of_response:1; }","duration":"159.018995ms","start":"2026-03-05T10:23:07.242027Z","end":"2026-03-05T10:23:07.401046Z","steps":["trace[773620912] 'process raft request' (duration: 158.878302ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T10:23:07.432437Z","caller":"traceutil/trace.go:171","msg":"trace[1475696219] transaction","detail":"{read_only:false; response_revision:17009; number_of_response:1; }","duration":"155.816876ms","start":"2026-03-05T10:23:07.276595Z","end":"2026-03-05T10:23:07.432412Z","steps":["trace[1475696219] 'process raft request' (duration: 155.692744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:23:07.689528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.133495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zcf7r\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-03-05T10:23:07.689605Z","caller":"traceutil/trace.go:171","msg":"trace[980258450] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zcf7r; range_end:; response_count:1; response_revision:17010; }","duration":"133.237917ms","start":"2026-03-05T10:23:07.556348Z","end":"2026-03-05T10:23:07.689586Z","steps":["trace[980258450] 'range keys from in-memory index tree' (duration: 132.950441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:23:07.689652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.945558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-05T10:23:07.68975Z","caller":"traceutil/trace.go:171","msg":"trace[1714853015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17010; }","duration":"187.06477ms","start":"2026-03-05T10:23:07.502664Z","end":"2026-03-05T10:23:07.689729Z","steps":["trace[1714853015] 'range keys from in-memory index tree' (duration: 186.810864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:23:07.68977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.775023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-05T10:23:07.689861Z","caller":"traceutil/trace.go:171","msg":"trace[1544320028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17010; }","duration":"186.872585ms","start":"2026-03-05T10:23:07.502968Z","end":"2026-03-05T10:23:07.68984Z","steps":["trace[1544320028] 'range keys from in-memory index tree' (duration: 186.65694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:23:07.689928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.9825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-05T10:23:07.690018Z","caller":"traceutil/trace.go:171","msg":"trace[2112159542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17010; }","duration":"189.101422ms","start":"2026-03-05T10:23:07.500896Z","end":"2026-03-05T10:23:07.689997Z","steps":["trace[2112159542] 'range keys from in-memory index tree' (duration: 188.837856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:23:08.259661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.559135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-03-05T10:23:08.259771Z","caller":"traceutil/trace.go:171","msg":"trace[748745274] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17014; }","duration":"387.711038ms","start":"2026-03-05T10:23:07.872033Z","end":"2026-03-05T10:23:08.259744Z","steps":["trace[748745274] 'range keys from in-memory index tree' (duration: 387.30017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T10:23:08.259828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T10:23:07.872013Z","time spent":"387.799709ms","remote":"127.0.0.1:43840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-05T10:23:23.779852Z","caller":"traceutil/trace.go:171","msg":"trace[735696692] transaction","detail":"{read_only:false; response_revision:17160; number_of_response:1; }","duration":"106.99754ms","start":"2026-03-05T10:23:23.67282Z","end":"2026-03-05T10:23:23.779818Z","steps":["trace[735696692] 'process raft request' (duration: 92.799019ms)","trace[735696692] 'compare' (duration: 14.040738ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T10:26:40.730796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16527} {"level":"info","ts":"2026-03-05T10:26:40.824697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16527,"took":"90.630945ms","hash":2027204634,"current-db-size-bytes":43503616,"current-db-size":"44 MB","current-db-size-in-use-bytes":24354816,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-05T10:26:40.82477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2027204634,"revision":16527,"compact-revision":14482}