{"level":"warn","ts":"2026-03-25T21:05:25.042196Z","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-25T21:05:25.042308Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-25T21:05:25.042385Z","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-25T21:05:25.042407Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-03-25T21:05:25.042437Z","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-25T21:05:25.043214Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-03-25T21:05:25.043317Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-25T21:05:25.047791Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.22846ms"} {"level":"info","ts":"2026-03-25T21:05:25.059406Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-03-25T21:05:25.05951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:05:25.059569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-03-25T21:05:25.059596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:05:25.059631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-03-25T21:05:25.059685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-03-25T21:05:25.065257Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:05:25.067277Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:05:25.06905Z","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-25T21:05:25.07133Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:05:25.071512Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:05:25.071685Z","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-25T21:05:25.071772Z","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-25T21:05:25.071782Z","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-25T21:05:25.073983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-03-25T21:05:25.074694Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-03-25T21:05:25.07682Z","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-25T21:05:25.077148Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-03-25T21:05:25.077407Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-03-25T21:05:25.077557Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:05:25.077607Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:05:25.260519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:05:25.26059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:05:25.260634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-03-25T21:05:25.260661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:05:25.260678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-03-25T21:05:25.260698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-03-25T21:05:25.260716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-03-25T21:05:25.261804Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:05:25.261851Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:05:25.261893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:05:25.261977Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:25.262257Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:05:25.262333Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:05:25.263033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:25.263156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:25.263192Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:25.265367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:05:25.265501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-03-25T21:05:53.577115Z","caller":"traceutil/trace.go:171","msg":"trace[513983260] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"126.872417ms","start":"2026-03-25T21:05:53.450216Z","end":"2026-03-25T21:05:53.577089Z","steps":["trace[513983260] 'process raft request' (duration: 122.049175ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:53.579763Z","caller":"traceutil/trace.go:171","msg":"trace[1788076080] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"103.523964ms","start":"2026-03-25T21:05:53.476208Z","end":"2026-03-25T21:05:53.579732Z","steps":["trace[1788076080] 'process raft request' (duration: 103.063604ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:53.581179Z","caller":"traceutil/trace.go:171","msg":"trace[1725885205] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"102.099223ms","start":"2026-03-25T21:05:53.479009Z","end":"2026-03-25T21:05:53.581108Z","steps":["trace[1725885205] 'process raft request' (duration: 100.606942ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:59.821532Z","caller":"traceutil/trace.go:171","msg":"trace[106208794] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"116.185421ms","start":"2026-03-25T21:05:59.705314Z","end":"2026-03-25T21:05:59.8215Z","steps":["trace[106208794] 'process raft request' (duration: 112.076534ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:00.329194Z","caller":"traceutil/trace.go:171","msg":"trace[917978856] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"433.474384ms","start":"2026-03-25T21:05:59.895695Z","end":"2026-03-25T21:06:00.32917Z","steps":["trace[917978856] 'read index received' (duration: 404.50241ms)","trace[917978856] 'applied index is now lower than readState.Index' (duration: 28.970964ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:00.329258Z","caller":"traceutil/trace.go:171","msg":"trace[137364015] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"446.584212ms","start":"2026-03-25T21:05:59.882623Z","end":"2026-03-25T21:06:00.329207Z","steps":["trace[137364015] 'process raft request' (duration: 417.624919ms)","trace[137364015] 'compare' (duration: 28.79417ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:00.329542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.833682ms","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-25T21:06:00.329707Z","caller":"traceutil/trace.go:171","msg":"trace[86690647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"433.954635ms","start":"2026-03-25T21:05:59.895677Z","end":"2026-03-25T21:06:00.329631Z","steps":["trace[86690647] 'agreement among raft nodes before linearized reading' (duration: 433.620967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:00.329753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:59.895619Z","time spent":"434.124429ms","remote":"127.0.0.1:49158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:06:00.329855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:59.882604Z","time spent":"446.847307ms","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:06:17.480301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.303991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kjh2t\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-03-25T21:06:17.480429Z","caller":"traceutil/trace.go:171","msg":"trace[996228774] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kjh2t; range_end:; response_count:1; response_revision:777; }","duration":"117.547096ms","start":"2026-03-25T21:06:17.362856Z","end":"2026-03-25T21:06:17.480403Z","steps":["trace[996228774] 'range keys from in-memory index tree' (duration: 117.056784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:17.480408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.715874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-25T21:06:17.48053Z","caller":"traceutil/trace.go:171","msg":"trace[471101049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"245.921369ms","start":"2026-03-25T21:06:17.234581Z","end":"2026-03-25T21:06:17.480502Z","steps":["trace[471101049] 'range keys from in-memory index tree' (duration: 245.479698ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:05.992793Z","caller":"traceutil/trace.go:171","msg":"trace[366704729] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"227.754883ms","start":"2026-03-25T21:07:05.764985Z","end":"2026-03-25T21:07:05.99274Z","steps":["trace[366704729] 'process raft request' (duration: 227.436847ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.135476Z","caller":"traceutil/trace.go:171","msg":"trace[194145927] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"347.390767ms","start":"2026-03-25T21:07:28.788064Z","end":"2026-03-25T21:07:29.135455Z","steps":["trace[194145927] 'read index received' (duration: 250.70362ms)","trace[194145927] 'applied index is now lower than readState.Index' (duration: 96.686047ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:29.135552Z","caller":"traceutil/trace.go:171","msg":"trace[1392650293] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"370.631399ms","start":"2026-03-25T21:07:28.764883Z","end":"2026-03-25T21:07:29.135514Z","steps":["trace[1392650293] 'process raft request' (duration: 273.935882ms)","trace[1392650293] 'compare' (duration: 96.088364ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:29.13567Z","caller":"traceutil/trace.go:171","msg":"trace[1195416837] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"181.756268ms","start":"2026-03-25T21:07:28.953894Z","end":"2026-03-25T21:07:29.135651Z","steps":["trace[1195416837] 'process raft request' (duration: 181.523173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.135715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.643862ms","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-25T21:07:29.135795Z","caller":"traceutil/trace.go:171","msg":"trace[1137553337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1827; }","duration":"347.775315ms","start":"2026-03-25T21:07:28.788001Z","end":"2026-03-25T21:07:29.135776Z","steps":["trace[1137553337] 'agreement among raft nodes before linearized reading' (duration: 347.582751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.135822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:28.787978Z","time spent":"347.837786ms","remote":"127.0.0.1:49158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:07:29.135881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:28.764859Z","time spent":"370.763051ms","remote":"127.0.0.1:49080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:07:29.725934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.621145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:29.726014Z","caller":"traceutil/trace.go:171","msg":"trace[922286000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1831; }","duration":"163.764088ms","start":"2026-03-25T21:07:29.562233Z","end":"2026-03-25T21:07:29.725997Z","steps":["trace[922286000] 'range keys from in-memory index tree' (duration: 163.546974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:14.565342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.489379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-25T21:08:14.565422Z","caller":"traceutil/trace.go:171","msg":"trace[1619467829] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"117.623951ms","start":"2026-03-25T21:08:14.447783Z","end":"2026-03-25T21:08:14.565407Z","steps":["trace[1619467829] 'range keys from in-memory index tree' (duration: 117.284574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:14.565578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.906699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:08:14.565594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.103111ms","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-25T21:08:14.565752Z","caller":"traceutil/trace.go:171","msg":"trace[1871099730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2129; }","duration":"203.353996ms","start":"2026-03-25T21:08:14.362369Z","end":"2026-03-25T21:08:14.565723Z","steps":["trace[1871099730] 'range keys from in-memory index tree' (duration: 202.773094ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:14.565729Z","caller":"traceutil/trace.go:171","msg":"trace[1548464438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2129; }","duration":"256.113863ms","start":"2026-03-25T21:08:14.309585Z","end":"2026-03-25T21:08:14.565699Z","steps":["trace[1548464438] 'count revisions from in-memory index tree' (duration: 255.664054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:14.565942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.335597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T21:08:14.566104Z","caller":"traceutil/trace.go:171","msg":"trace[1378495381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2129; }","duration":"246.573621ms","start":"2026-03-25T21:08:14.319498Z","end":"2026-03-25T21:08:14.566072Z","steps":["trace[1378495381] 'range keys from in-memory index tree' (duration: 245.986838ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:11.847578Z","caller":"traceutil/trace.go:171","msg":"trace[468933920] transaction","detail":"{read_only:false; response_revision:3788; number_of_response:1; }","duration":"108.548534ms","start":"2026-03-25T21:12:11.739004Z","end":"2026-03-25T21:12:11.847552Z","steps":["trace[468933920] 'process raft request' (duration: 108.195525ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:25.718425Z","caller":"traceutil/trace.go:171","msg":"trace[842836949] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4826; }","duration":"157.62808ms","start":"2026-03-25T21:13:25.560769Z","end":"2026-03-25T21:13:25.718396Z","steps":["trace[842836949] 'read index received' (duration: 11.380093ms)","trace[842836949] 'applied index is now lower than readState.Index' (duration: 146.246227ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:25.718512Z","caller":"traceutil/trace.go:171","msg":"trace[679059458] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"161.616679ms","start":"2026-03-25T21:13:25.556853Z","end":"2026-03-25T21:13:25.71847Z","steps":["trace[679059458] 'process raft request' (duration: 161.477146ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:25.718541Z","caller":"traceutil/trace.go:171","msg":"trace[2108625464] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"168.854149ms","start":"2026-03-25T21:13:25.549676Z","end":"2026-03-25T21:13:25.718531Z","steps":["trace[2108625464] 'process raft request' (duration: 168.482911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:25.718812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.000728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:25.71894Z","caller":"traceutil/trace.go:171","msg":"trace[1362143421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4650; }","duration":"158.156951ms","start":"2026-03-25T21:13:25.56075Z","end":"2026-03-25T21:13:25.718907Z","steps":["trace[1362143421] 'agreement among raft nodes before linearized reading' (duration: 157.866565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:33.833694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.112318ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T21:13:33.833827Z","caller":"traceutil/trace.go:171","msg":"trace[1607351435] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5121; }","duration":"187.029713ms","start":"2026-03-25T21:13:33.646778Z","end":"2026-03-25T21:13:33.833808Z","steps":["trace[1607351435] 'read index received' (duration: 52.665919ms)","trace[1607351435] 'applied index is now lower than readState.Index' (duration: 134.362184ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:33.834125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.321049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf3e1225-9a98-4b87-91de-6dd480dc2738\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-25T21:13:33.83421Z","caller":"traceutil/trace.go:171","msg":"trace[2080157965] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf3e1225-9a98-4b87-91de-6dd480dc2738; range_end:; response_count:1; response_revision:4936; }","duration":"187.417262ms","start":"2026-03-25T21:13:33.646772Z","end":"2026-03-25T21:13:33.834189Z","steps":["trace[2080157965] 'agreement among raft nodes before linearized reading' (duration: 187.149216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:33.836011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.48215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-t7zw9.18a031fc00e42323\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-03-25T21:13:33.836077Z","caller":"traceutil/trace.go:171","msg":"trace[1897339913] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-t7zw9.18a031fc00e42323; range_end:; response_count:1; response_revision:4937; }","duration":"163.556442ms","start":"2026-03-25T21:13:33.672505Z","end":"2026-03-25T21:13:33.836062Z","steps":["trace[1897339913] 'agreement among raft nodes before linearized reading' (duration: 163.369508ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:34.003726Z","caller":"traceutil/trace.go:171","msg":"trace[986375385] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"162.009948ms","start":"2026-03-25T21:13:33.84167Z","end":"2026-03-25T21:13:34.00368Z","steps":["trace[986375385] 'process raft request' (duration: 161.61238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.355109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.17187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:45.355311Z","caller":"traceutil/trace.go:171","msg":"trace[483883207] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5356; }","duration":"162.69452ms","start":"2026-03-25T21:13:45.192594Z","end":"2026-03-25T21:13:45.355288Z","steps":["trace[483883207] 'read index received' (duration: 8.188424ms)","trace[483883207] 'applied index is now lower than readState.Index' (duration: 154.504266ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:45.355571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.993887ms","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-25T21:13:45.355612Z","caller":"traceutil/trace.go:171","msg":"trace[513548226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5163; }","duration":"163.085919ms","start":"2026-03-25T21:13:45.192513Z","end":"2026-03-25T21:13:45.355599Z","steps":["trace[513548226] 'agreement among raft nodes before linearized reading' (duration: 162.906185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.355621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.757623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:13:45.355667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.294879ms","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-25T21:13:45.355704Z","caller":"traceutil/trace.go:171","msg":"trace[2112335212] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5163; }","duration":"108.878186ms","start":"2026-03-25T21:13:45.246802Z","end":"2026-03-25T21:13:45.35568Z","steps":["trace[2112335212] 'agreement among raft nodes before linearized reading' (duration: 108.717223ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.355762Z","caller":"traceutil/trace.go:171","msg":"trace[944379053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5163; }","duration":"112.420271ms","start":"2026-03-25T21:13:45.243322Z","end":"2026-03-25T21:13:45.355742Z","steps":["trace[944379053] 'agreement among raft nodes before linearized reading' (duration: 112.168645ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.355926Z","caller":"traceutil/trace.go:171","msg":"trace[805531762] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"169.216129ms","start":"2026-03-25T21:13:45.186675Z","end":"2026-03-25T21:13:45.355891Z","steps":["trace[805531762] 'process raft request' (duration: 14.13359ms)","trace[805531762] 'compare' (duration: 153.993406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:45.591865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.659448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:45.592061Z","caller":"traceutil/trace.go:171","msg":"trace[872448458] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"134.648894ms","start":"2026-03-25T21:13:45.45739Z","end":"2026-03-25T21:13:45.592039Z","steps":["trace[872448458] 'process raft request' (duration: 12.69678ms)","trace[872448458] 'compare' (duration: 121.440053ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:53.74587Z","caller":"traceutil/trace.go:171","msg":"trace[6486795] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"103.146544ms","start":"2026-03-25T21:13:53.642689Z","end":"2026-03-25T21:13:53.745835Z","steps":["trace[6486795] 'read index received' (duration: 102.91263ms)","trace[6486795] 'applied index is now lower than readState.Index' (duration: 232.354µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:53.746042Z","caller":"traceutil/trace.go:171","msg":"trace[1902545194] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"125.399378ms","start":"2026-03-25T21:13:53.62059Z","end":"2026-03-25T21:13:53.745989Z","steps":["trace[1902545194] 'process raft request' (duration: 125.04266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:53.746319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.593693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-25T21:13:53.746437Z","caller":"traceutil/trace.go:171","msg":"trace[1575934700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5336; }","duration":"103.790858ms","start":"2026-03-25T21:13:53.642622Z","end":"2026-03-25T21:13:53.746413Z","steps":["trace[1575934700] 'agreement among raft nodes before linearized reading' (duration: 103.41997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:53.906434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.502091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ff8f6b3-3cd8-49f4-8c51-f171b9a3d16d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-25T21:13:53.90656Z","caller":"traceutil/trace.go:171","msg":"trace[274828406] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ff8f6b3-3cd8-49f4-8c51-f171b9a3d16d; range_end:; response_count:1; response_revision:5337; }","duration":"119.695245ms","start":"2026-03-25T21:13:53.786837Z","end":"2026-03-25T21:13:53.906533Z","steps":["trace[274828406] 'agreement among raft nodes before linearized reading' (duration: 64.656395ms)","trace[274828406] 'range keys from in-memory index tree' (duration: 54.765725ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:54.030441Z","caller":"traceutil/trace.go:171","msg":"trace[1058060828] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"114.863443ms","start":"2026-03-25T21:13:53.915557Z","end":"2026-03-25T21:13:54.03042Z","steps":["trace[1058060828] 'process raft request' (duration: 114.754401ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:15:25.422626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3039} {"level":"info","ts":"2026-03-25T21:15:25.508106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3039,"took":"83.843836ms","hash":4133451253,"current-db-size-bytes":58089472,"current-db-size":"58 MB","current-db-size-in-use-bytes":31883264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-25T21:15:25.508176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4133451253,"revision":3039,"compact-revision":-1} {"level":"info","ts":"2026-03-25T21:19:51.258206Z","caller":"traceutil/trace.go:171","msg":"trace[1201680113] transaction","detail":"{read_only:false; response_revision:8124; number_of_response:1; }","duration":"128.669083ms","start":"2026-03-25T21:19:51.129515Z","end":"2026-03-25T21:19:51.258184Z","steps":["trace[1201680113] 'process raft request' (duration: 128.312874ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:02.598384Z","caller":"traceutil/trace.go:171","msg":"trace[187997207] linearizableReadLoop","detail":"{readStateIndex:8470; appliedIndex:8469; }","duration":"227.003988ms","start":"2026-03-25T21:20:02.371346Z","end":"2026-03-25T21:20:02.59835Z","steps":["trace[187997207] 'read index received' (duration: 226.788123ms)","trace[187997207] 'applied index is now lower than readState.Index' (duration: 214.405µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:02.598544Z","caller":"traceutil/trace.go:171","msg":"trace[173803308] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"232.172408ms","start":"2026-03-25T21:20:02.366295Z","end":"2026-03-25T21:20:02.598467Z","steps":["trace[173803308] 'process raft request' (duration: 231.858721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:02.5988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.007757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-03-25T21:20:02.598784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.392436ms","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-25T21:20:02.598886Z","caller":"traceutil/trace.go:171","msg":"trace[319883469] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8182; }","duration":"218.19886ms","start":"2026-03-25T21:20:02.380662Z","end":"2026-03-25T21:20:02.598861Z","steps":["trace[319883469] 'agreement among raft nodes before linearized reading' (duration: 217.917544ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:02.5989Z","caller":"traceutil/trace.go:171","msg":"trace[902607652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8182; }","duration":"227.56128ms","start":"2026-03-25T21:20:02.371314Z","end":"2026-03-25T21:20:02.598875Z","steps":["trace[902607652] 'agreement among raft nodes before linearized reading' (duration: 227.211802ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:02.713249Z","caller":"traceutil/trace.go:171","msg":"trace[642129075] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"109.048494ms","start":"2026-03-25T21:20:02.604151Z","end":"2026-03-25T21:20:02.713199Z","steps":["trace[642129075] 'process raft request' (duration: 30.022639ms)","trace[642129075] 'compare' (duration: 78.723079ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:02.713467Z","caller":"traceutil/trace.go:171","msg":"trace[557081586] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"107.791028ms","start":"2026-03-25T21:20:02.605632Z","end":"2026-03-25T21:20:02.713423Z","steps":["trace[557081586] 'process raft request' (duration: 107.500242ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:03.499717Z","caller":"traceutil/trace.go:171","msg":"trace[1228403373] linearizableReadLoop","detail":"{readStateIndex:8476; appliedIndex:8475; }","duration":"115.881679ms","start":"2026-03-25T21:20:03.383808Z","end":"2026-03-25T21:20:03.49969Z","steps":["trace[1228403373] 'read index received' (duration: 115.625414ms)","trace[1228403373] 'applied index is now lower than readState.Index' (duration: 255.045µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:03.499818Z","caller":"traceutil/trace.go:171","msg":"trace[193002068] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"188.698931ms","start":"2026-03-25T21:20:03.311078Z","end":"2026-03-25T21:20:03.499777Z","steps":["trace[193002068] 'process raft request' (duration: 188.422445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:03.500052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.207847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-25T21:20:03.500158Z","caller":"traceutil/trace.go:171","msg":"trace[1790338078] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8187; }","duration":"116.403301ms","start":"2026-03-25T21:20:03.383731Z","end":"2026-03-25T21:20:03.500134Z","steps":["trace[1790338078] 'agreement among raft nodes before linearized reading' (duration: 116.063063ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:04.848823Z","caller":"traceutil/trace.go:171","msg":"trace[500771999] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"125.967185ms","start":"2026-03-25T21:20:04.722816Z","end":"2026-03-25T21:20:04.848783Z","steps":["trace[500771999] 'process raft request' (duration: 125.709289ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:04.859985Z","caller":"traceutil/trace.go:171","msg":"trace[1007858042] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"136.127372ms","start":"2026-03-25T21:20:04.723806Z","end":"2026-03-25T21:20:04.859933Z","steps":["trace[1007858042] 'process raft request' (duration: 135.858026ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:25.429047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6144} {"level":"info","ts":"2026-03-25T21:20:25.532036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6144,"took":"101.282289ms","hash":2067135792,"current-db-size-bytes":58089472,"current-db-size":"58 MB","current-db-size-in-use-bytes":22925312,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-25T21:20:25.532114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2067135792,"revision":6144,"compact-revision":3039} {"level":"info","ts":"2026-03-25T21:20:32.269441Z","caller":"traceutil/trace.go:171","msg":"trace[1227016777] linearizableReadLoop","detail":"{readStateIndex:8695; appliedIndex:8694; }","duration":"301.755321ms","start":"2026-03-25T21:20:31.967652Z","end":"2026-03-25T21:20:32.269408Z","steps":["trace[1227016777] 'read index received' (duration: 263.70543ms)","trace[1227016777] 'applied index is now lower than readState.Index' (duration: 38.04837ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:32.269735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.070437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-25T21:20:32.269774Z","caller":"traceutil/trace.go:171","msg":"trace[201166080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8397; }","duration":"302.187209ms","start":"2026-03-25T21:20:31.967577Z","end":"2026-03-25T21:20:32.269765Z","steps":["trace[201166080] 'agreement among raft nodes before linearized reading' (duration: 301.962344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:32.269807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:31.967551Z","time spent":"302.247601ms","remote":"127.0.0.1:49158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:20:32.269885Z","caller":"traceutil/trace.go:171","msg":"trace[2096065072] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"376.565455ms","start":"2026-03-25T21:20:31.893288Z","end":"2026-03-25T21:20:32.269854Z","steps":["trace[2096065072] 'process raft request' (duration: 338.028074ms)","trace[2096065072] 'compare' (duration: 37.932828ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:32.270046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:31.893237Z","time spent":"376.724999ms","remote":"127.0.0.1:49158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:20:44.581103Z","caller":"traceutil/trace.go:171","msg":"trace[1058470522] linearizableReadLoop","detail":"{readStateIndex:8808; appliedIndex:8807; }","duration":"141.830652ms","start":"2026-03-25T21:20:44.439243Z","end":"2026-03-25T21:20:44.581073Z","steps":["trace[1058470522] 'read index received' (duration: 90.350825ms)","trace[1058470522] 'applied index is now lower than readState.Index' (duration: 51.477767ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:44.581229Z","caller":"traceutil/trace.go:171","msg":"trace[595216284] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"142.917796ms","start":"2026-03-25T21:20:44.438296Z","end":"2026-03-25T21:20:44.581214Z","steps":["trace[595216284] 'process raft request' (duration: 91.357777ms)","trace[595216284] 'compare' (duration: 51.266722ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:44.58158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.310122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-7knxz\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-03-25T21:20:44.581644Z","caller":"traceutil/trace.go:171","msg":"trace[1733929301] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-7knxz; range_end:; response_count:1; response_revision:8504; }","duration":"142.407474ms","start":"2026-03-25T21:20:44.439222Z","end":"2026-03-25T21:20:44.581629Z","steps":["trace[1733929301] 'agreement among raft nodes before linearized reading' (duration: 142.20079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:44.631971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.205072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-25T21:20:44.63206Z","caller":"traceutil/trace.go:171","msg":"trace[1057979500] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8505; }","duration":"105.356115ms","start":"2026-03-25T21:20:44.526685Z","end":"2026-03-25T21:20:44.632042Z","steps":["trace[1057979500] 'agreement among raft nodes before linearized reading' (duration: 105.162521ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:44.734752Z","caller":"traceutil/trace.go:171","msg":"trace[13592707] linearizableReadLoop","detail":"{readStateIndex:8810; appliedIndex:8809; }","duration":"103.280731ms","start":"2026-03-25T21:20:44.631454Z","end":"2026-03-25T21:20:44.734735Z","steps":["trace[13592707] 'read index received' (duration: 90.321464ms)","trace[13592707] 'applied index is now lower than readState.Index' (duration: 12.958447ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:44.734795Z","caller":"traceutil/trace.go:171","msg":"trace[327792887] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8506; }","duration":"145.699705ms","start":"2026-03-25T21:20:44.589058Z","end":"2026-03-25T21:20:44.734757Z","steps":["trace[327792887] 'process raft request' (duration: 132.732329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:44.735142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.169585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w6dxg\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-25T21:20:44.735191Z","caller":"traceutil/trace.go:171","msg":"trace[1865561473] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w6dxg; range_end:; response_count:1; response_revision:8506; }","duration":"146.256137ms","start":"2026-03-25T21:20:44.588923Z","end":"2026-03-25T21:20:44.735179Z","steps":["trace[1865561473] 'agreement among raft nodes before linearized reading' (duration: 145.90802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:44.735163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.257587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7knxz\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-03-25T21:20:44.735715Z","caller":"traceutil/trace.go:171","msg":"trace[389430264] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7knxz; range_end:; response_count:1; response_revision:8506; }","duration":"146.86056ms","start":"2026-03-25T21:20:44.588832Z","end":"2026-03-25T21:20:44.735692Z","steps":["trace[389430264] 'agreement among raft nodes before linearized reading' (duration: 146.116814ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:44.748661Z","caller":"traceutil/trace.go:171","msg":"trace[1988003305] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"106.713174ms","start":"2026-03-25T21:20:44.64192Z","end":"2026-03-25T21:20:44.748633Z","steps":["trace[1988003305] 'process raft request' (duration: 106.426748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:44.748957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.924155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-25T21:20:44.749093Z","caller":"traceutil/trace.go:171","msg":"trace[503701396] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8507; }","duration":"112.103419ms","start":"2026-03-25T21:20:44.63696Z","end":"2026-03-25T21:20:44.749063Z","steps":["trace[503701396] 'agreement among raft nodes before linearized reading' (duration: 111.68114ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:45.612392Z","caller":"traceutil/trace.go:171","msg":"trace[1728575318] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"211.522048ms","start":"2026-03-25T21:20:45.400841Z","end":"2026-03-25T21:20:45.612363Z","steps":["trace[1728575318] 'process raft request' (duration: 211.291693ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:45.652547Z","caller":"traceutil/trace.go:171","msg":"trace[1486419451] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"230.373831ms","start":"2026-03-25T21:20:45.422131Z","end":"2026-03-25T21:20:45.652505Z","steps":["trace[1486419451] 'process raft request' (duration: 230.126436ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:21:25.691695Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f1.wal"} {"level":"info","ts":"2026-03-25T21:21:58.385932Z","caller":"traceutil/trace.go:171","msg":"trace[581652851] linearizableReadLoop","detail":"{readStateIndex:9560; appliedIndex:9559; }","duration":"141.085351ms","start":"2026-03-25T21:21:58.244807Z","end":"2026-03-25T21:21:58.385892Z","steps":["trace[581652851] 'read index received' (duration: 99.139536ms)","trace[581652851] 'applied index is now lower than readState.Index' (duration: 41.944315ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:21:58.386024Z","caller":"traceutil/trace.go:171","msg":"trace[1816123259] transaction","detail":"{read_only:false; response_revision:9237; number_of_response:1; }","duration":"230.530681ms","start":"2026-03-25T21:21:58.155449Z","end":"2026-03-25T21:21:58.38598Z","steps":["trace[1816123259] 'process raft request' (duration: 188.500514ms)","trace[1816123259] 'compare' (duration: 41.625899ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:21:58.386265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.435409ms","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-25T21:21:58.386336Z","caller":"traceutil/trace.go:171","msg":"trace[588603118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9237; }","duration":"141.570112ms","start":"2026-03-25T21:21:58.244732Z","end":"2026-03-25T21:21:58.386302Z","steps":["trace[588603118] 'agreement among raft nodes before linearized reading' (duration: 141.319517ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:22:57.669315Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T21:22:57.69103Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T21:22:57.691212Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T21:25:25.436503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8315} {"level":"info","ts":"2026-03-25T21:25:25.527833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8315,"took":"87.675371ms","hash":3109903930,"current-db-size-bytes":58089472,"current-db-size":"58 MB","current-db-size-in-use-bytes":24891392,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-25T21:25:25.52791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3109903930,"revision":8315,"compact-revision":6144} {"level":"info","ts":"2026-03-25T21:30:25.443165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10705} {"level":"info","ts":"2026-03-25T21:30:25.526604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10705,"took":"80.836763ms","hash":109891897,"current-db-size-bytes":58089472,"current-db-size":"58 MB","current-db-size-in-use-bytes":20373504,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-25T21:30:25.526694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":109891897,"revision":10705,"compact-revision":8315}