{"level":"warn","ts":"2026-05-20T12:51:47.957093Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T12:51:47.957212Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.59: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.59:2380","--initial-cluster=instance=https://199.19.213.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.59: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-05-20T12:51:47.957303Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T12:51:47.957323Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-05-20T12:51:47.95735Z","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-05-20T12:51:47.958284Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"]} {"level":"info","ts":"2026-05-20T12:51:47.958385Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.59: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-05-20T12:51:47.962568Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.922045ms"} {"level":"info","ts":"2026-05-20T12:51:47.973069Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"41c06612bf92f90","cluster-id":"7f792254ff9520b"} {"level":"info","ts":"2026-05-20T12:51:47.973158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T12:51:47.973186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 0"} {"level":"info","ts":"2026-05-20T12:51:47.973197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 41c06612bf92f90 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T12:51:47.973207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 1"} {"level":"info","ts":"2026-05-20T12:51:47.973243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"warn","ts":"2026-05-20T12:51:47.98219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T12:51:47.984989Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T12:51:47.986397Z","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-05-20T12:51:47.989703Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"41c06612bf92f90","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T12:51:47.990882Z","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-05-20T12:51:47.992139Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"41c06612bf92f90","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T12:51:47.992242Z","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-05-20T12:51:47.992335Z","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-05-20T12:51:47.992654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"info","ts":"2026-05-20T12:51:47.992783Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","added-peer-id":"41c06612bf92f90","added-peer-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-05-20T12:51:47.99483Z","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-05-20T12:51:47.994903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-05-20T12:51:47.994951Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-05-20T12:51:47.995332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"41c06612bf92f90","initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T12:51:47.99542Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T12:51:48.374348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T12:51:48.374416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T12:51:48.374454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgPreVoteResp from 41c06612bf92f90 at term 1"} {"level":"info","ts":"2026-05-20T12:51:48.374471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became candidate at term 2"} {"level":"info","ts":"2026-05-20T12:51:48.37448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgVoteResp from 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-05-20T12:51:48.374493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became leader at term 2"} {"level":"info","ts":"2026-05-20T12:51:48.374504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 41c06612bf92f90 elected leader 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-05-20T12:51:48.375706Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:51:48.376685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"41c06612bf92f90","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.59:2379]}","request-path":"/0/members/41c06612bf92f90/attributes","cluster-id":"7f792254ff9520b","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T12:51:48.376631Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:51:48.376834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T12:51:48.377071Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:51:48.377265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:51:48.379421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T12:51:48.385619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T12:51:48.385649Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T12:51:48.385674Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T12:51:48.387902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.59:2379"} {"level":"info","ts":"2026-05-20T12:51:52.182949Z","caller":"traceutil/trace.go:171","msg":"trace[515163482] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"304.758091ms","start":"2026-05-20T12:51:51.878146Z","end":"2026-05-20T12:51:52.182904Z","steps":["trace[515163482] 'process raft request' (duration: 236.75991ms)","trace[515163482] 'compare' (duration: 67.550837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:51:52.184065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:51.878128Z","time spent":"305.056153ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T12:51:59.016039Z","caller":"traceutil/trace.go:171","msg":"trace[432629416] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"373.408924ms","start":"2026-05-20T12:51:58.642609Z","end":"2026-05-20T12:51:59.016018Z","steps":["trace[432629416] 'read index received' (duration: 373.190403ms)","trace[432629416] 'applied index is now lower than readState.Index' (duration: 217.691µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:51:59.016221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.187927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-20T12:51:59.01628Z","caller":"traceutil/trace.go:171","msg":"trace[1247054871] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"323.306677ms","start":"2026-05-20T12:51:58.69296Z","end":"2026-05-20T12:51:59.016267Z","steps":["trace[1247054871] 'agreement among raft nodes before linearized reading' (duration: 323.193186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.016247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.599847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-05-20T12:51:59.016317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:58.692938Z","time spent":"323.370818ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-20T12:51:59.016356Z","caller":"traceutil/trace.go:171","msg":"trace[1424362648] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:303; }","duration":"373.768708ms","start":"2026-05-20T12:51:58.642559Z","end":"2026-05-20T12:51:59.016328Z","steps":["trace[1424362648] 'agreement among raft nodes before linearized reading' (duration: 373.587575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.016407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:58.642532Z","time spent":"373.863889ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-20T12:51:59.016645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.907135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T12:51:59.016675Z","caller":"traceutil/trace.go:171","msg":"trace[1113355925] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:303; }","duration":"122.980776ms","start":"2026-05-20T12:51:58.893685Z","end":"2026-05-20T12:51:59.016666Z","steps":["trace[1113355925] 'agreement among raft nodes before linearized reading' (duration: 122.887076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.016796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.909412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T12:51:59.016817Z","caller":"traceutil/trace.go:171","msg":"trace[228340785] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:303; }","duration":"172.970193ms","start":"2026-05-20T12:51:58.843841Z","end":"2026-05-20T12:51:59.016811Z","steps":["trace[228340785] 'agreement among raft nodes before linearized reading' (duration: 172.931183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.016888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.139079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-20T12:51:59.016887Z","caller":"traceutil/trace.go:171","msg":"trace[452906700] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"384.980536ms","start":"2026-05-20T12:51:58.631126Z","end":"2026-05-20T12:51:59.016107Z","steps":["trace[452906700] 'process raft request' (duration: 384.697034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.017002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.21882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-20T12:51:59.017034Z","caller":"traceutil/trace.go:171","msg":"trace[1747472215] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:303; }","duration":"273.29396ms","start":"2026-05-20T12:51:58.743732Z","end":"2026-05-20T12:51:59.017026Z","steps":["trace[1747472215] 'agreement among raft nodes before linearized reading' (duration: 273.23668ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:51:59.016913Z","caller":"traceutil/trace.go:171","msg":"trace[1652556795] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:303; }","duration":"224.25687ms","start":"2026-05-20T12:51:58.792648Z","end":"2026-05-20T12:51:59.016905Z","steps":["trace[1652556795] 'agreement among raft nodes before linearized reading' (duration: 224.21816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.017383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:58.631111Z","time spent":"385.895894ms","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T12:51:59.32859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.22172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T12:51:59.328936Z","caller":"traceutil/trace.go:171","msg":"trace[80915973] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"301.791796ms","start":"2026-05-20T12:51:59.0271Z","end":"2026-05-20T12:51:59.328892Z","steps":["trace[80915973] 'process raft request' (duration: 301.620935ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:51:59.329028Z","caller":"traceutil/trace.go:171","msg":"trace[197227653] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"301.242441ms","start":"2026-05-20T12:51:59.027771Z","end":"2026-05-20T12:51:59.329013Z","steps":["trace[197227653] 'process raft request' (duration: 301.16888ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:51:59.329051Z","caller":"traceutil/trace.go:171","msg":"trace[726635742] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"304.905541ms","start":"2026-05-20T12:51:59.024089Z","end":"2026-05-20T12:51:59.328995Z","steps":["trace[726635742] 'process raft request' (duration: 11.967255ms)","trace[726635742] 'compare' (duration: 292.056479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:51:59.32913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.027743Z","time spent":"301.329901ms","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T12:51:59.329152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.024046Z","time spent":"305.059312ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T12:51:59.329161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.02706Z","time spent":"301.977748ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T12:51:59.329155Z","caller":"traceutil/trace.go:171","msg":"trace[1280751771] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"301.651415ms","start":"2026-05-20T12:51:59.027469Z","end":"2026-05-20T12:51:59.32912Z","steps":["trace[1280751771] 'process raft request' (duration: 301.437253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.329283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.027463Z","time spent":"301.763266ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T12:51:59.329303Z","caller":"traceutil/trace.go:171","msg":"trace[840641844] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"302.049718ms","start":"2026-05-20T12:51:59.027235Z","end":"2026-05-20T12:51:59.329285Z","steps":["trace[840641844] 'process raft request' (duration: 301.541994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.329366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.027227Z","time spent":"302.108638ms","remote":"127.0.0.1:40070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T12:51:59.329563Z","caller":"traceutil/trace.go:171","msg":"trace[1099962348] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"302.22158ms","start":"2026-05-20T12:51:59.027323Z","end":"2026-05-20T12:51:59.329545Z","steps":["trace[1099962348] 'process raft request' (duration: 301.485494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.329637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.027314Z","time spent":"302.28923ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T12:51:59.329781Z","caller":"traceutil/trace.go:171","msg":"trace[1057957418] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:315; }","duration":"286.093702ms","start":"2026-05-20T12:51:59.043678Z","end":"2026-05-20T12:51:59.329772Z","steps":["trace[1057957418] 'read index received' (duration: 282.431653ms)","trace[1057957418] 'applied index is now lower than readState.Index' (duration: 3.661239ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:51:59.329849Z","caller":"traceutil/trace.go:171","msg":"trace[1859042906] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"301.553883ms","start":"2026-05-20T12:51:59.028289Z","end":"2026-05-20T12:51:59.329843Z","steps":["trace[1859042906] 'process raft request' (duration: 301.337182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.329896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.028277Z","time spent":"301.582983ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:51:59.329903Z","caller":"traceutil/trace.go:171","msg":"trace[1267824359] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"301.630465ms","start":"2026-05-20T12:51:59.028258Z","end":"2026-05-20T12:51:59.329888Z","steps":["trace[1267824359] 'process raft request' (duration: 300.722688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.329928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.256915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-20T12:51:59.329965Z","caller":"traceutil/trace.go:171","msg":"trace[877715356] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:313; }","duration":"286.344025ms","start":"2026-05-20T12:51:59.04361Z","end":"2026-05-20T12:51:59.329954Z","steps":["trace[877715356] 'agreement among raft nodes before linearized reading' (duration: 286.273973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.329987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.028251Z","time spent":"301.686436ms","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T12:51:59.330022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.642155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-20T12:51:59.330049Z","caller":"traceutil/trace.go:171","msg":"trace[1194431292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"268.706036ms","start":"2026-05-20T12:51:59.061335Z","end":"2026-05-20T12:51:59.330041Z","steps":["trace[1194431292] 'agreement among raft nodes before linearized reading' (duration: 268.651375ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:51:59.330102Z","caller":"traceutil/trace.go:171","msg":"trace[903113274] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"301.695336ms","start":"2026-05-20T12:51:59.028395Z","end":"2026-05-20T12:51:59.330091Z","steps":["trace[903113274] 'process raft request' (duration: 301.335952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.330254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.02839Z","time spent":"301.828707ms","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:51:59.330455Z","caller":"traceutil/trace.go:171","msg":"trace[464125470] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"301.951536ms","start":"2026-05-20T12:51:59.027361Z","end":"2026-05-20T12:51:59.329313Z","steps":["trace[464125470] 'process raft request' (duration: 301.493383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:51:59.330515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:51:59.027355Z","time spent":"303.128867ms","remote":"127.0.0.1:39776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:52:22.762168Z","caller":"traceutil/trace.go:171","msg":"trace[292085270] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"111.027812ms","start":"2026-05-20T12:52:22.651097Z","end":"2026-05-20T12:52:22.762125Z","steps":["trace[292085270] 'process raft request' (duration: 104.978261ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:52:29.522021Z","caller":"traceutil/trace.go:171","msg":"trace[938972887] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"196.27185ms","start":"2026-05-20T12:52:29.325718Z","end":"2026-05-20T12:52:29.52199Z","steps":["trace[938972887] 'read index received' (duration: 194.996332ms)","trace[938972887] 'applied index is now lower than readState.Index' (duration: 1.274248ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:52:29.522243Z","caller":"traceutil/trace.go:171","msg":"trace[1512211124] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"238.404157ms","start":"2026-05-20T12:52:29.283824Z","end":"2026-05-20T12:52:29.522228Z","steps":["trace[1512211124] 'process raft request' (duration: 237.401181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.522457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.693563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T12:52:29.522516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.749783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:52:29.522603Z","caller":"traceutil/trace.go:171","msg":"trace[241140223] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:583; }","duration":"196.885344ms","start":"2026-05-20T12:52:29.325662Z","end":"2026-05-20T12:52:29.522547Z","steps":["trace[241140223] 'agreement among raft nodes before linearized reading' (duration: 196.596223ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:52:29.522637Z","caller":"traceutil/trace.go:171","msg":"trace[816045808] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:583; }","duration":"196.964255ms","start":"2026-05-20T12:52:29.32565Z","end":"2026-05-20T12:52:29.522614Z","steps":["trace[816045808] 'agreement among raft nodes before linearized reading' (duration: 196.634853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.522606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.000441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:52:29.522856Z","caller":"traceutil/trace.go:171","msg":"trace[1942948346] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:583; }","duration":"163.285354ms","start":"2026-05-20T12:52:29.359535Z","end":"2026-05-20T12:52:29.52282Z","steps":["trace[1942948346] 'agreement among raft nodes before linearized reading' (duration: 162.922861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.52447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.852754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T12:52:29.524548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.766104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:52:29.524591Z","caller":"traceutil/trace.go:171","msg":"trace[769886783] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:583; }","duration":"164.859044ms","start":"2026-05-20T12:52:29.359717Z","end":"2026-05-20T12:52:29.524576Z","steps":["trace[769886783] 'agreement among raft nodes before linearized reading' (duration: 164.795804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.524687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.053396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:52:29.524708Z","caller":"traceutil/trace.go:171","msg":"trace[459784180] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:583; }","duration":"121.081986ms","start":"2026-05-20T12:52:29.40362Z","end":"2026-05-20T12:52:29.524702Z","steps":["trace[459784180] 'agreement among raft nodes before linearized reading' (duration: 121.046196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.524729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.787234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:52:29.524794Z","caller":"traceutil/trace.go:171","msg":"trace[1557927755] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:583; }","duration":"164.901764ms","start":"2026-05-20T12:52:29.359879Z","end":"2026-05-20T12:52:29.52478Z","steps":["trace[1557927755] 'agreement among raft nodes before linearized reading' (duration: 164.783374ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:52:29.524695Z","caller":"traceutil/trace.go:171","msg":"trace[1989161972] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:583; }","duration":"120.993896ms","start":"2026-05-20T12:52:29.403567Z","end":"2026-05-20T12:52:29.524561Z","steps":["trace[1989161972] 'agreement among raft nodes before linearized reading' (duration: 120.852605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.524991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.353557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:52:29.525171Z","caller":"traceutil/trace.go:171","msg":"trace[1465579182] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:583; }","duration":"121.550409ms","start":"2026-05-20T12:52:29.403607Z","end":"2026-05-20T12:52:29.525157Z","steps":["trace[1465579182] 'agreement among raft nodes before linearized reading' (duration: 121.267177ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:52:29.931147Z","caller":"traceutil/trace.go:171","msg":"trace[1311149825] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"344.696266ms","start":"2026-05-20T12:52:29.586416Z","end":"2026-05-20T12:52:29.931112Z","steps":["trace[1311149825] 'process raft request' (duration: 344.507955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:29.931333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:52:29.586383Z","time spent":"344.855427ms","remote":"127.0.0.1:40096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T12:52:31.370026Z","caller":"traceutil/trace.go:171","msg":"trace[846791569] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"105.643879ms","start":"2026-05-20T12:52:31.264345Z","end":"2026-05-20T12:52:31.369989Z","steps":["trace[846791569] 'process raft request' (duration: 104.817734ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:52:46.426519Z","caller":"traceutil/trace.go:171","msg":"trace[1390253817] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"236.000912ms","start":"2026-05-20T12:52:46.190496Z","end":"2026-05-20T12:52:46.426497Z","steps":["trace[1390253817] 'read index received' (duration: 235.810893ms)","trace[1390253817] 'applied index is now lower than readState.Index' (duration: 189.409µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:52:46.426945Z","caller":"traceutil/trace.go:171","msg":"trace[1341761394] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"249.329386ms","start":"2026-05-20T12:52:46.177606Z","end":"2026-05-20T12:52:46.426935Z","steps":["trace[1341761394] 'process raft request' (duration: 248.639806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:52:46.426957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.06167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T12:52:46.426957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.438791ms","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-05-20T12:52:46.427049Z","caller":"traceutil/trace.go:171","msg":"trace[169824806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"141.212937ms","start":"2026-05-20T12:52:46.285814Z","end":"2026-05-20T12:52:46.427027Z","steps":["trace[169824806] 'agreement among raft nodes before linearized reading' (duration: 141.009728ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:52:46.42705Z","caller":"traceutil/trace.go:171","msg":"trace[2045967819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"236.600968ms","start":"2026-05-20T12:52:46.190432Z","end":"2026-05-20T12:52:46.427033Z","steps":["trace[2045967819] 'agreement among raft nodes before linearized reading' (duration: 236.310866ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:54:14.858959Z","caller":"traceutil/trace.go:171","msg":"trace[562937887] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"107.03246ms","start":"2026-05-20T12:54:14.751893Z","end":"2026-05-20T12:54:14.858926Z","steps":["trace[562937887] 'read index received' (duration: 106.617713ms)","trace[562937887] 'applied index is now lower than readState.Index' (duration: 413.347µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:54:14.859174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.254054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:54:14.859268Z","caller":"traceutil/trace.go:171","msg":"trace[231009362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"107.425867ms","start":"2026-05-20T12:54:14.751828Z","end":"2026-05-20T12:54:14.859253Z","steps":["trace[231009362] 'agreement among raft nodes before linearized reading' (duration: 107.229564ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:54:14.859122Z","caller":"traceutil/trace.go:171","msg":"trace[542891234] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"177.190124ms","start":"2026-05-20T12:54:14.681877Z","end":"2026-05-20T12:54:14.859067Z","steps":["trace[542891234] 'process raft request' (duration: 176.855158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:54:15.1128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.260439ms","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-05-20T12:54:15.11289Z","caller":"traceutil/trace.go:171","msg":"trace[1620038924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"100.422813ms","start":"2026-05-20T12:54:15.012447Z","end":"2026-05-20T12:54:15.11287Z","steps":["trace[1620038924] 'range keys from in-memory index tree' (duration: 100.019444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:54:15.112918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.379231ms","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-05-20T12:54:15.113039Z","caller":"traceutil/trace.go:171","msg":"trace[729686441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1857; }","duration":"127.627686ms","start":"2026-05-20T12:54:14.985384Z","end":"2026-05-20T12:54:15.113011Z","steps":["trace[729686441] 'range keys from in-memory index tree' (duration: 127.112844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:54:49.05719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.149611ms","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-05-20T12:54:49.057275Z","caller":"traceutil/trace.go:171","msg":"trace[1579898881] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"129.293603ms","start":"2026-05-20T12:54:48.927963Z","end":"2026-05-20T12:54:49.057256Z","steps":["trace[1579898881] 'range keys from in-memory index tree' (duration: 128.915127ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:54:49.443104Z","caller":"traceutil/trace.go:171","msg":"trace[1306658358] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"107.374688ms","start":"2026-05-20T12:54:49.335686Z","end":"2026-05-20T12:54:49.443061Z","steps":["trace[1306658358] 'process raft request' (duration: 98.701974ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:56:28.920775Z","caller":"traceutil/trace.go:171","msg":"trace[1382927226] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2846; }","duration":"106.790696ms","start":"2026-05-20T12:56:28.813961Z","end":"2026-05-20T12:56:28.920751Z","steps":["trace[1382927226] 'read index received' (duration: 91.876851ms)","trace[1382927226] 'applied index is now lower than readState.Index' (duration: 14.912975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:56:28.920944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.979048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-20T12:56:28.920971Z","caller":"traceutil/trace.go:171","msg":"trace[906952821] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2743; }","duration":"107.06275ms","start":"2026-05-20T12:56:28.813901Z","end":"2026-05-20T12:56:28.920964Z","steps":["trace[906952821] 'agreement among raft nodes before linearized reading' (duration: 106.942288ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:56:28.921572Z","caller":"traceutil/trace.go:171","msg":"trace[1126312153] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"120.854592ms","start":"2026-05-20T12:56:28.799978Z","end":"2026-05-20T12:56:28.920832Z","steps":["trace[1126312153] 'process raft request' (duration: 105.806036ms)","trace[1126312153] 'compare' (duration: 14.653832ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:29.214769Z","caller":"traceutil/trace.go:171","msg":"trace[521573154] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"105.074168ms","start":"2026-05-20T13:00:29.109663Z","end":"2026-05-20T13:00:29.214738Z","steps":["trace[521573154] 'process raft request' (duration: 74.459003ms)","trace[521573154] 'compare' (duration: 30.086358ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:37.781252Z","caller":"traceutil/trace.go:171","msg":"trace[173865367] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4947; }","duration":"113.084419ms","start":"2026-05-20T13:00:37.668139Z","end":"2026-05-20T13:00:37.781223Z","steps":["trace[173865367] 'read index received' (duration: 573.899µs)","trace[173865367] 'applied index is now lower than readState.Index' (duration: 112.50741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:37.781343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.19258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:37.781422Z","caller":"traceutil/trace.go:171","msg":"trace[241592068] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4767; }","duration":"113.277452ms","start":"2026-05-20T13:00:37.668125Z","end":"2026-05-20T13:00:37.781403Z","steps":["trace[241592068] 'agreement among raft nodes before linearized reading' (duration: 113.16701ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:37.78162Z","caller":"traceutil/trace.go:171","msg":"trace[963038813] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"108.387381ms","start":"2026-05-20T13:00:37.673216Z","end":"2026-05-20T13:00:37.781603Z","steps":["trace[963038813] 'process raft request' (duration: 107.919665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:37.781914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.880488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-05-20T13:00:37.781981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.861698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-20T13:00:37.782008Z","caller":"traceutil/trace.go:171","msg":"trace[1435740697] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4770; }","duration":"108.899039ms","start":"2026-05-20T13:00:37.6731Z","end":"2026-05-20T13:00:37.781999Z","steps":["trace[1435740697] 'agreement among raft nodes before linearized reading' (duration: 108.800108ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:37.781993Z","caller":"traceutil/trace.go:171","msg":"trace[911497134] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"108.057207ms","start":"2026-05-20T13:00:37.673914Z","end":"2026-05-20T13:00:37.781971Z","steps":["trace[911497134] 'process raft request' (duration: 107.541929ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:37.78201Z","caller":"traceutil/trace.go:171","msg":"trace[1797377494] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4770; }","duration":"108.99366ms","start":"2026-05-20T13:00:37.673Z","end":"2026-05-20T13:00:37.781993Z","steps":["trace[1797377494] 'agreement among raft nodes before linearized reading' (duration: 108.719626ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:39.72931Z","caller":"traceutil/trace.go:171","msg":"trace[405108472] linearizableReadLoop","detail":"{readStateIndex:5014; appliedIndex:5013; }","duration":"226.632216ms","start":"2026-05-20T13:00:39.502651Z","end":"2026-05-20T13:00:39.729283Z","steps":["trace[405108472] 'read index received' (duration: 130.883341ms)","trace[405108472] 'applied index is now lower than readState.Index' (duration: 95.747765ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:39.7294Z","caller":"traceutil/trace.go:171","msg":"trace[2119094701] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"226.871749ms","start":"2026-05-20T13:00:39.502494Z","end":"2026-05-20T13:00:39.729365Z","steps":["trace[2119094701] 'process raft request' (duration: 131.027632ms)","trace[2119094701] 'compare' (duration: 95.527462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:39.72958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.145343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-05-20T13:00:39.729633Z","caller":"traceutil/trace.go:171","msg":"trace[1304549667] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4829; }","duration":"227.266184ms","start":"2026-05-20T13:00:39.50235Z","end":"2026-05-20T13:00:39.729616Z","steps":["trace[1304549667] 'agreement among raft nodes before linearized reading' (duration: 227.086622ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:39.731925Z","caller":"traceutil/trace.go:171","msg":"trace[127488210] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"228.902598ms","start":"2026-05-20T13:00:39.503003Z","end":"2026-05-20T13:00:39.731905Z","steps":["trace[127488210] 'process raft request' (duration: 228.404791ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:39.731976Z","caller":"traceutil/trace.go:171","msg":"trace[1575834910] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"228.472223ms","start":"2026-05-20T13:00:39.503477Z","end":"2026-05-20T13:00:39.73195Z","steps":["trace[1575834910] 'process raft request' (duration: 228.296541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:39.732004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.301798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-20T13:00:39.732078Z","caller":"traceutil/trace.go:171","msg":"trace[1527901306] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4832; }","duration":"223.40704ms","start":"2026-05-20T13:00:39.508648Z","end":"2026-05-20T13:00:39.732055Z","steps":["trace[1527901306] 'agreement among raft nodes before linearized reading' (duration: 223.191736ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:39.734306Z","caller":"traceutil/trace.go:171","msg":"trace[1830110034] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"228.860918ms","start":"2026-05-20T13:00:39.502798Z","end":"2026-05-20T13:00:39.731659Z","steps":["trace[1830110034] 'process raft request' (duration: 228.483413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.705654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.45376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:53.705754Z","caller":"traceutil/trace.go:171","msg":"trace[1910466956] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5017; }","duration":"198.589372ms","start":"2026-05-20T13:00:53.507143Z","end":"2026-05-20T13:00:53.705732Z","steps":["trace[1910466956] 'count revisions from in-memory index tree' (duration: 198.403229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.706159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.050499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-20T13:00:53.706273Z","caller":"traceutil/trace.go:171","msg":"trace[66974056] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5017; }","duration":"192.225141ms","start":"2026-05-20T13:00:53.514024Z","end":"2026-05-20T13:00:53.706249Z","steps":["trace[66974056] 'range keys from in-memory index tree' (duration: 191.794135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.70627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.722531ms","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-05-20T13:00:53.706332Z","caller":"traceutil/trace.go:171","msg":"trace[74499357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5017; }","duration":"200.810113ms","start":"2026-05-20T13:00:53.505505Z","end":"2026-05-20T13:00:53.706316Z","steps":["trace[74499357] 'range keys from in-memory index tree' (duration: 200.59957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.706485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.621625ms","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-05-20T13:00:53.706535Z","caller":"traceutil/trace.go:171","msg":"trace[1142265651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5017; }","duration":"201.705646ms","start":"2026-05-20T13:00:53.504817Z","end":"2026-05-20T13:00:53.706523Z","steps":["trace[1142265651] 'range keys from in-memory index tree' (duration: 201.430992ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:54.374482Z","caller":"traceutil/trace.go:171","msg":"trace[1377212969] linearizableReadLoop","detail":"{readStateIndex:5220; appliedIndex:5219; }","duration":"100.41771ms","start":"2026-05-20T13:00:54.274042Z","end":"2026-05-20T13:00:54.37446Z","steps":["trace[1377212969] 'read index received' (duration: 100.293738ms)","trace[1377212969] 'applied index is now lower than readState.Index' (duration: 122.962µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:54.374823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.739995ms","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-05-20T13:00:54.374918Z","caller":"traceutil/trace.go:171","msg":"trace[1480041690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5029; }","duration":"100.913327ms","start":"2026-05-20T13:00:54.273896Z","end":"2026-05-20T13:00:54.374903Z","steps":["trace[1480041690] 'agreement among raft nodes before linearized reading' (duration: 100.608453ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:54.375114Z","caller":"traceutil/trace.go:171","msg":"trace[802744663] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"119.663863ms","start":"2026-05-20T13:00:54.254879Z","end":"2026-05-20T13:00:54.374543Z","steps":["trace[802744663] 'process raft request' (duration: 119.334818ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:48.567481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2848} {"level":"info","ts":"2026-05-20T13:01:48.637953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2848,"took":"69.170404ms","hash":2249297718,"current-db-size-bytes":51859456,"current-db-size":"52 MB","current-db-size-in-use-bytes":28205056,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-20T13:01:48.638061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2249297718,"revision":2848,"compact-revision":-1} {"level":"info","ts":"2026-05-20T13:03:18.16531Z","caller":"traceutil/trace.go:171","msg":"trace[1291554360] linearizableReadLoop","detail":"{readStateIndex:6245; appliedIndex:6244; }","duration":"415.275709ms","start":"2026-05-20T13:03:17.749999Z","end":"2026-05-20T13:03:18.165275Z","steps":["trace[1291554360] 'read index received' (duration: 414.992045ms)","trace[1291554360] 'applied index is now lower than readState.Index' (duration: 282.384µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:03:18.165373Z","caller":"traceutil/trace.go:171","msg":"trace[1541058255] transaction","detail":"{read_only:false; response_revision:6023; number_of_response:1; }","duration":"592.720953ms","start":"2026-05-20T13:03:17.572621Z","end":"2026-05-20T13:03:18.165342Z","steps":["trace[1541058255] 'process raft request' (duration: 592.387988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.16553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:17.572602Z","time spent":"592.842214ms","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:03:18.165523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.488951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:18.165582Z","caller":"traceutil/trace.go:171","msg":"trace[2028238970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6023; }","duration":"415.622532ms","start":"2026-05-20T13:03:17.749943Z","end":"2026-05-20T13:03:18.165566Z","steps":["trace[2028238970] 'agreement among raft nodes before linearized reading' (duration: 415.500151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.165662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:17.749926Z","time spent":"415.723334ms","remote":"127.0.0.1:39558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T13:03:18.172298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.087828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T13:03:18.172365Z","caller":"traceutil/trace.go:171","msg":"trace[411178326] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:6024; }","duration":"306.160429ms","start":"2026-05-20T13:03:17.866186Z","end":"2026-05-20T13:03:18.172347Z","steps":["trace[411178326] 'agreement among raft nodes before linearized reading' (duration: 306.024817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.172435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:17.866109Z","time spent":"306.315101ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-05-20T13:03:18.172173Z","caller":"traceutil/trace.go:171","msg":"trace[1202273513] transaction","detail":"{read_only:false; response_revision:6024; number_of_response:1; }","duration":"182.5677ms","start":"2026-05-20T13:03:17.989577Z","end":"2026-05-20T13:03:18.172145Z","steps":["trace[1202273513] 'process raft request' (duration: 182.414278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.955263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427413337518292109,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:03:19.57736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427413337518292109,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:03:19.836331Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.405934909s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-20T13:03:19.839591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.507565Z","time spent":"1.332018023s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-20T13:03:19.840731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591036921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:19.841337Z","caller":"traceutil/trace.go:171","msg":"trace[1815387672] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:6024; }","duration":"1.591704219s","start":"2026-05-20T13:03:18.249616Z","end":"2026-05-20T13:03:19.84132Z","steps":["trace[1815387672] 'count revisions from in-memory index tree' (duration: 1.59094734s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.841393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.249594Z","time spent":"1.59178807s","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-20T13:03:19.843152Z","caller":"traceutil/trace.go:171","msg":"trace[69357970] transaction","detail":"{read_only:false; response_revision:6026; number_of_response:1; }","duration":"1.155784344s","start":"2026-05-20T13:03:18.687292Z","end":"2026-05-20T13:03:19.843076Z","steps":["trace[69357970] 'process raft request' (duration: 1.155652682s)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:03:19.843185Z","caller":"traceutil/trace.go:171","msg":"trace[1292568722] transaction","detail":"{read_only:false; response_revision:6025; number_of_response:1; }","duration":"1.412861887s","start":"2026-05-20T13:03:18.430287Z","end":"2026-05-20T13:03:19.843149Z","steps":["trace[1292568722] 'process raft request' (duration: 1.406462755s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.843318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.687257Z","time spent":"1.155967708s","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:03:19.843408Z","caller":"traceutil/trace.go:171","msg":"trace[2048590154] transaction","detail":"{read_only:false; response_revision:6027; number_of_response:1; }","duration":"1.081186291s","start":"2026-05-20T13:03:18.762063Z","end":"2026-05-20T13:03:19.843249Z","steps":["trace[2048590154] 'process raft request' (duration: 1.080961058s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.843455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.430263Z","time spent":"1.41303574s","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:03:19.843587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.762041Z","time spent":"1.081443274s","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:03:19.84321Z","caller":"traceutil/trace.go:171","msg":"trace[1703961090] linearizableReadLoop","detail":"{readStateIndex:6250; appliedIndex:6247; }","duration":"1.388020946s","start":"2026-05-20T13:03:18.455175Z","end":"2026-05-20T13:03:19.843196Z","steps":["trace[1703961090] 'read index received' (duration: 1.381484682s)","trace[1703961090] 'applied index is now lower than readState.Index' (duration: 6.535394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:03:19.843785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.627259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:19.844983Z","caller":"traceutil/trace.go:171","msg":"trace[1183983884] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:6027; }","duration":"446.863165ms","start":"2026-05-20T13:03:19.398092Z","end":"2026-05-20T13:03:19.844955Z","steps":["trace[1183983884] 'agreement among raft nodes before linearized reading' (duration: 445.655929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.843896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.298278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-20T13:03:19.845085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.398061Z","time spent":"447.007647ms","remote":"127.0.0.1:41696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-05-20T13:03:19.845126Z","caller":"traceutil/trace.go:171","msg":"trace[1263956537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6027; }","duration":"742.538675ms","start":"2026-05-20T13:03:19.10256Z","end":"2026-05-20T13:03:19.845098Z","steps":["trace[1263956537] 'agreement among raft nodes before linearized reading' (duration: 741.214997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.845196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.102546Z","time spent":"742.636956ms","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T13:03:19.8439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388736085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-05-20T13:03:19.845356Z","caller":"traceutil/trace.go:171","msg":"trace[1698146702] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6027; }","duration":"1.390189525s","start":"2026-05-20T13:03:18.455152Z","end":"2026-05-20T13:03:19.845341Z","steps":["trace[1698146702] 'agreement among raft nodes before linearized reading' (duration: 1.388560483s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.845395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.455039Z","time spent":"1.390343626s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-20T13:03:19.843944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318941573s","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-05-20T13:03:19.845551Z","caller":"traceutil/trace.go:171","msg":"trace[328904412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6027; }","duration":"1.320662495s","start":"2026-05-20T13:03:18.524878Z","end":"2026-05-20T13:03:19.84554Z","steps":["trace[328904412] 'agreement among raft nodes before linearized reading' (duration: 1.318909002s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.845587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.524832Z","time spent":"1.320745417s","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T13:03:19.844015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317659747s","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-05-20T13:03:19.846261Z","caller":"traceutil/trace.go:171","msg":"trace[1132724395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6027; }","duration":"1.319923526s","start":"2026-05-20T13:03:18.526328Z","end":"2026-05-20T13:03:19.846251Z","steps":["trace[1132724395] 'agreement among raft nodes before linearized reading' (duration: 1.317635576s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.84633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.526321Z","time spent":"1.319997417s","remote":"127.0.0.1:39850","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-05-20T13:03:19.843963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.27593ms","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-05-20T13:03:19.846403Z","caller":"traceutil/trace.go:171","msg":"trace[1114704127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6027; }","duration":"658.729252ms","start":"2026-05-20T13:03:19.187657Z","end":"2026-05-20T13:03:19.846386Z","steps":["trace[1114704127] 'agreement among raft nodes before linearized reading' (duration: 656.27888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.846467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.187648Z","time spent":"658.805913ms","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T13:03:19.844027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.707149ms","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-05-20T13:03:19.846552Z","caller":"traceutil/trace.go:171","msg":"trace[173849934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6027; }","duration":"739.240892ms","start":"2026-05-20T13:03:19.107295Z","end":"2026-05-20T13:03:19.846536Z","steps":["trace[173849934] 'agreement among raft nodes before linearized reading' (duration: 736.652688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.846654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.107277Z","time spent":"739.327864ms","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T13:03:19.844084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318108703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-20T13:03:19.846725Z","caller":"traceutil/trace.go:171","msg":"trace[1449986347] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6027; }","duration":"1.320793127s","start":"2026-05-20T13:03:18.525915Z","end":"2026-05-20T13:03:19.846708Z","steps":["trace[1449986347] 'agreement among raft nodes before linearized reading' (duration: 1.318117402s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.846794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.525895Z","time spent":"1.320882589s","remote":"127.0.0.1:39850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-20T13:03:19.844235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.722728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T13:03:19.846934Z","caller":"traceutil/trace.go:171","msg":"trace[1268808329] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6027; }","duration":"269.445382ms","start":"2026-05-20T13:03:19.577475Z","end":"2026-05-20T13:03:19.84692Z","steps":["trace[1268808329] 'agreement among raft nodes before linearized reading' (duration: 266.718046ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:06:48.577577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5434} {"level":"info","ts":"2026-05-20T13:06:48.663013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5434,"took":"84.123639ms","hash":278402363,"current-db-size-bytes":51859456,"current-db-size":"52 MB","current-db-size-in-use-bytes":18362368,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-20T13:06:48.66308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":278402363,"revision":5434,"compact-revision":2848}