{"level":"warn","ts":"2026-04-20T05:49:40.920799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:49:40.920924Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T05:49:40.921011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:49:40.921027Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-20T05:49:40.921049Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T05:49:40.921716Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-20T05:49:40.921805Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T05:49:40.925889Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.809441ms"} {"level":"info","ts":"2026-04-20T05:49:40.933503Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-20T05:49:40.933596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T05:49:40.93363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-20T05:49:40.933642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T05:49:40.933662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-20T05:49:40.933706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-20T05:49:40.938939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T05:49:40.940205Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T05:49:40.941455Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T05:49:40.943485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T05:49:40.94383Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T05:49:40.943944Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T05:49:40.944202Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T05:49:40.94427Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T05:49:40.945396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-20T05:49:40.946143Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-20T05:49:40.948499Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T05:49:40.948688Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-20T05:49:40.948764Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-20T05:49:40.948848Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T05:49:40.948894Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T05:49:41.334042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T05:49:41.334115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T05:49:41.334142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-20T05:49:41.334158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-20T05:49:41.334166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-20T05:49:41.334178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-20T05:49:41.334189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-20T05:49:41.435474Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:49:41.435705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:49:41.435709Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T05:49:41.435846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:49:41.436073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T05:49:41.436144Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T05:49:41.439033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-04-20T05:49:41.439318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T05:49:41.592335Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:49:41.592718Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:49:41.592863Z","caller":"traceutil/trace.go:171","msg":"trace[230412539] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"106.097938ms","start":"2026-04-20T05:49:41.486745Z","end":"2026-04-20T05:49:41.592843Z","steps":["trace[230412539] 'read index received' (duration: 105.611578ms)","trace[230412539] 'applied index is now lower than readState.Index' (duration: 484.7µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:49:41.592909Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-20T05:49:41.593216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.399244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T05:49:41.593342Z","caller":"traceutil/trace.go:171","msg":"trace[2011459775] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"106.579139ms","start":"2026-04-20T05:49:41.486739Z","end":"2026-04-20T05:49:41.593318Z","steps":["trace[2011459775] 'agreement among raft nodes before linearized reading' (duration: 106.21336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:49:41.593242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.298331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T05:49:41.593846Z","caller":"traceutil/trace.go:171","msg":"trace[1798104657] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"106.942966ms","start":"2026-04-20T05:49:41.48689Z","end":"2026-04-20T05:49:41.593833Z","steps":["trace[1798104657] 'agreement among raft nodes before linearized reading' (duration: 106.23053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:49:55.231572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.118817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:49:55.231725Z","caller":"traceutil/trace.go:171","msg":"trace[79451633] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"198.935993ms","start":"2026-04-20T05:49:55.032746Z","end":"2026-04-20T05:49:55.231682Z","steps":["trace[79451633] 'process raft request' (duration: 93.147092ms)","trace[79451633] 'compare' (duration: 105.002934ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:12.569263Z","caller":"traceutil/trace.go:171","msg":"trace[992426368] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"232.61702ms","start":"2026-04-20T05:50:12.336623Z","end":"2026-04-20T05:50:12.56924Z","steps":["trace[992426368] 'read index received' (duration: 232.355515ms)","trace[992426368] 'applied index is now lower than readState.Index' (duration: 260.555µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:12.569328Z","caller":"traceutil/trace.go:171","msg":"trace[1923437109] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"240.873965ms","start":"2026-04-20T05:50:12.328424Z","end":"2026-04-20T05:50:12.569298Z","steps":["trace[1923437109] 'process raft request' (duration: 240.650821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:12.569484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.839785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-20T05:50:12.569525Z","caller":"traceutil/trace.go:171","msg":"trace[1432901713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:546; }","duration":"232.937737ms","start":"2026-04-20T05:50:12.336576Z","end":"2026-04-20T05:50:12.569514Z","steps":["trace[1432901713] 'agreement among raft nodes before linearized reading' (duration: 232.757523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:16.826432Z","caller":"traceutil/trace.go:171","msg":"trace[1899685080] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"110.618934ms","start":"2026-04-20T05:50:16.715793Z","end":"2026-04-20T05:50:16.826412Z","steps":["trace[1899685080] 'process raft request' (duration: 110.170394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:17.747893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.468199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:50:17.748031Z","caller":"traceutil/trace.go:171","msg":"trace[1644519965] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"603.83875ms","start":"2026-04-20T05:50:17.144165Z","end":"2026-04-20T05:50:17.748004Z","steps":["trace[1644519965] 'process raft request' (duration: 72.153266ms)","trace[1644519965] 'compare' (duration: 531.322985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:50:17.74817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:17.144125Z","time spent":"603.999233ms","remote":"127.0.0.1:44130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:50:17.76667Z","caller":"traceutil/trace.go:171","msg":"trace[605620211] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"421.668312ms","start":"2026-04-20T05:50:17.344953Z","end":"2026-04-20T05:50:17.766621Z","steps":["trace[605620211] 'read index received' (duration: 416.163975ms)","trace[605620211] 'applied index is now lower than readState.Index' (duration: 5.503237ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:17.766789Z","caller":"traceutil/trace.go:171","msg":"trace[355450180] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"426.791482ms","start":"2026-04-20T05:50:17.339947Z","end":"2026-04-20T05:50:17.766739Z","steps":["trace[355450180] 'process raft request' (duration: 423.128024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:17.766931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.953678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T05:50:17.767024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.363639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:50:17.767051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.428345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T05:50:17.767049Z","caller":"traceutil/trace.go:171","msg":"trace[1457938177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"422.118872ms","start":"2026-04-20T05:50:17.344909Z","end":"2026-04-20T05:50:17.767028Z","steps":["trace[1457938177] 'agreement among raft nodes before linearized reading' (duration: 421.906017ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:17.767075Z","caller":"traceutil/trace.go:171","msg":"trace[568799829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"149.4572ms","start":"2026-04-20T05:50:17.617603Z","end":"2026-04-20T05:50:17.76706Z","steps":["trace[568799829] 'agreement among raft nodes before linearized reading' (duration: 149.303116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:17.767067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:17.339923Z","time spent":"426.966285ms","remote":"127.0.0.1:43972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:50:17.767084Z","caller":"traceutil/trace.go:171","msg":"trace[1542942465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"372.501677ms","start":"2026-04-20T05:50:17.394571Z","end":"2026-04-20T05:50:17.767073Z","steps":["trace[1542942465] 'agreement among raft nodes before linearized reading' (duration: 372.261872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:17.767094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:17.344891Z","time spent":"422.192523ms","remote":"127.0.0.1:44130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T05:50:17.767116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:17.394528Z","time spent":"372.582029ms","remote":"127.0.0.1:44130","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-04-20T05:50:17.767176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.901306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kkp7l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T05:50:17.767266Z","caller":"traceutil/trace.go:171","msg":"trace[2041413371] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kkp7l; range_end:; response_count:1; response_revision:578; }","duration":"311.021518ms","start":"2026-04-20T05:50:17.456196Z","end":"2026-04-20T05:50:17.767218Z","steps":["trace[2041413371] 'agreement among raft nodes before linearized reading' (duration: 310.871645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:17.767303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:17.456177Z","time spent":"311.117511ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-kkp7l\" "} {"level":"warn","ts":"2026-04-20T05:50:33.200022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.977851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T05:50:33.200117Z","caller":"traceutil/trace.go:171","msg":"trace[1858371446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"103.198257ms","start":"2026-04-20T05:50:33.096905Z","end":"2026-04-20T05:50:33.200103Z","steps":["trace[1858371446] 'range keys from in-memory index tree' (duration: 102.824978ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:04.249064Z","caller":"traceutil/trace.go:171","msg":"trace[1752702524] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"199.034966ms","start":"2026-04-20T05:51:04.050006Z","end":"2026-04-20T05:51:04.249041Z","steps":["trace[1752702524] 'process raft request' (duration: 198.914744ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:44.998449Z","caller":"traceutil/trace.go:171","msg":"trace[612034252] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"109.265684ms","start":"2026-04-20T05:51:44.889145Z","end":"2026-04-20T05:51:44.998411Z","steps":["trace[612034252] 'compare' (duration: 99.063836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:45.678093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.782116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-20T05:51:45.67818Z","caller":"traceutil/trace.go:171","msg":"trace[31740236] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1789; }","duration":"102.924199ms","start":"2026-04-20T05:51:45.575234Z","end":"2026-04-20T05:51:45.678158Z","steps":["trace[31740236] 'range keys from in-memory index tree' (duration: 102.552901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:29.885602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.95657ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T05:52:29.885721Z","caller":"traceutil/trace.go:171","msg":"trace[644888886] linearizableReadLoop","detail":"{readStateIndex:2166; appliedIndex:2165; }","duration":"228.038628ms","start":"2026-04-20T05:52:29.65766Z","end":"2026-04-20T05:52:29.885699Z","steps":["trace[644888886] 'read index received' (duration: 49.601µs)","trace[644888886] 'applied index is now lower than readState.Index' (duration: 227.987397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:52:29.886092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.414005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T05:52:29.886347Z","caller":"traceutil/trace.go:171","msg":"trace[2096095259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2086; }","duration":"228.711322ms","start":"2026-04-20T05:52:29.657609Z","end":"2026-04-20T05:52:29.886321Z","steps":["trace[2096095259] 'agreement among raft nodes before linearized reading' (duration: 228.280573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:29.886623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.756164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:52:29.886815Z","caller":"traceutil/trace.go:171","msg":"trace[106901333] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2086; }","duration":"191.876107ms","start":"2026-04-20T05:52:29.694789Z","end":"2026-04-20T05:52:29.886665Z","steps":["trace[106901333] 'agreement among raft nodes before linearized reading' (duration: 191.730504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:52.38208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.4714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:53:52.382266Z","caller":"traceutil/trace.go:171","msg":"trace[473051018] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2581; }","duration":"101.729505ms","start":"2026-04-20T05:53:52.28051Z","end":"2026-04-20T05:53:52.38224Z","steps":["trace[473051018] 'range keys from in-memory index tree' (duration: 101.368938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:52.38214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.079233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T05:53:52.382452Z","caller":"traceutil/trace.go:171","msg":"trace[1879987049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2581; }","duration":"109.46215ms","start":"2026-04-20T05:53:52.27297Z","end":"2026-04-20T05:53:52.382432Z","steps":["trace[1879987049] 'range keys from in-memory index tree' (duration: 108.904199ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:56:28.118071Z","caller":"traceutil/trace.go:171","msg":"trace[1704601400] transaction","detail":"{read_only:false; response_revision:3596; number_of_response:1; }","duration":"223.331801ms","start":"2026-04-20T05:56:27.894695Z","end":"2026-04-20T05:56:28.118027Z","steps":["trace[1704601400] 'process raft request' (duration: 200.208388ms)","trace[1704601400] 'compare' (duration: 23.021561ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:57:41.752305Z","caller":"traceutil/trace.go:171","msg":"trace[2002929902] linearizableReadLoop","detail":"{readStateIndex:4725; appliedIndex:4724; }","duration":"102.072298ms","start":"2026-04-20T05:57:41.650186Z","end":"2026-04-20T05:57:41.752258Z","steps":["trace[2002929902] 'read index received' (duration: 66.268447ms)","trace[2002929902] 'applied index is now lower than readState.Index' (duration: 35.802281ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:57:41.752434Z","caller":"traceutil/trace.go:171","msg":"trace[1397383081] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4553; }","duration":"119.107399ms","start":"2026-04-20T05:57:41.63329Z","end":"2026-04-20T05:57:41.752397Z","steps":["trace[1397383081] 'process raft request' (duration: 83.160676ms)","trace[1397383081] 'compare' (duration: 35.724019ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:57:41.752608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.394384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-20T05:57:41.752687Z","caller":"traceutil/trace.go:171","msg":"trace[39607453] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4553; }","duration":"102.531137ms","start":"2026-04-20T05:57:41.650135Z","end":"2026-04-20T05:57:41.752666Z","steps":["trace[39607453] 'agreement among raft nodes before linearized reading' (duration: 102.308972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:57:41.753325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.981026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-zbxxs-tls-ncxzs\" ","response":"range_response_count:1 size:2629"} {"level":"info","ts":"2026-04-20T05:57:41.753384Z","caller":"traceutil/trace.go:171","msg":"trace[1257294387] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-zbxxs-tls-ncxzs; range_end:; response_count:1; response_revision:4554; }","duration":"103.053148ms","start":"2026-04-20T05:57:41.650317Z","end":"2026-04-20T05:57:41.75337Z","steps":["trace[1257294387] 'agreement among raft nodes before linearized reading' (duration: 102.876714ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:57:41.753466Z","caller":"traceutil/trace.go:171","msg":"trace[1777899579] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"103.003177ms","start":"2026-04-20T05:57:41.650443Z","end":"2026-04-20T05:57:41.753446Z","steps":["trace[1777899579] 'process raft request' (duration: 102.63371ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:57:46.5002Z","caller":"traceutil/trace.go:171","msg":"trace[1003683739] linearizableReadLoop","detail":"{readStateIndex:4915; appliedIndex:4914; }","duration":"128.000589ms","start":"2026-04-20T05:57:46.372172Z","end":"2026-04-20T05:57:46.500172Z","steps":["trace[1003683739] 'read index received' (duration: 127.712853ms)","trace[1003683739] 'applied index is now lower than readState.Index' (duration: 286.356µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:57:46.500376Z","caller":"traceutil/trace.go:171","msg":"trace[1136113755] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"134.703571ms","start":"2026-04-20T05:57:46.36566Z","end":"2026-04-20T05:57:46.500364Z","steps":["trace[1136113755] 'process raft request' (duration: 134.245391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:57:46.500636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.206607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-04-20T05:57:46.50074Z","caller":"traceutil/trace.go:171","msg":"trace[1604260136] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4737; }","duration":"107.35479ms","start":"2026-04-20T05:57:46.393363Z","end":"2026-04-20T05:57:46.500718Z","steps":["trace[1604260136] 'agreement among raft nodes before linearized reading' (duration: 107.094575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:57:46.500796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.620362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zbxxs-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-04-20T05:57:46.500859Z","caller":"traceutil/trace.go:171","msg":"trace[154661737] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-zbxxs-tls; range_end:; response_count:1; response_revision:4737; }","duration":"128.801996ms","start":"2026-04-20T05:57:46.372038Z","end":"2026-04-20T05:57:46.50084Z","steps":["trace[154661737] 'agreement among raft nodes before linearized reading' (duration: 128.600792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:57:46.668305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.914767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-20T05:57:46.668288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.829118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-531fdc1a-1a5a-4e81-be49-69df5aadec94\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T05:57:46.668376Z","caller":"traceutil/trace.go:171","msg":"trace[2123768382] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4737; }","duration":"112.06046ms","start":"2026-04-20T05:57:46.556298Z","end":"2026-04-20T05:57:46.668358Z","steps":["trace[2123768382] 'range keys from in-memory index tree' (duration: 111.549959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:57:46.668414Z","caller":"traceutil/trace.go:171","msg":"trace[1085149426] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-531fdc1a-1a5a-4e81-be49-69df5aadec94; range_end:; response_count:1; response_revision:4737; }","duration":"114.051873ms","start":"2026-04-20T05:57:46.554335Z","end":"2026-04-20T05:57:46.668387Z","steps":["trace[1085149426] 'range keys from in-memory index tree' (duration: 113.466741ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:58:03.984482Z","caller":"traceutil/trace.go:171","msg":"trace[1216231059] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"231.791992ms","start":"2026-04-20T05:58:03.752647Z","end":"2026-04-20T05:58:03.984439Z","steps":["trace[1216231059] 'process raft request' (duration: 212.192935ms)","trace[1216231059] 'compare' (duration: 19.061416ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:58:03.984783Z","caller":"traceutil/trace.go:171","msg":"trace[1798700442] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"232.041458ms","start":"2026-04-20T05:58:03.752728Z","end":"2026-04-20T05:58:03.984769Z","steps":["trace[1798700442] 'process raft request' (duration: 231.64686ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:58:03.984897Z","caller":"traceutil/trace.go:171","msg":"trace[1194091016] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"152.238589ms","start":"2026-04-20T05:58:03.832637Z","end":"2026-04-20T05:58:03.984875Z","steps":["trace[1194091016] 'process raft request' (duration: 151.868722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:04.254083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.654783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T05:58:04.254178Z","caller":"traceutil/trace.go:171","msg":"trace[176777977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5099; }","duration":"124.816077ms","start":"2026-04-20T05:58:04.129345Z","end":"2026-04-20T05:58:04.254161Z","steps":["trace[176777977] 'range keys from in-memory index tree' (duration: 124.369486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:04.254195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.700622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T05:58:04.254282Z","caller":"traceutil/trace.go:171","msg":"trace[196441040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5099; }","duration":"130.843935ms","start":"2026-04-20T05:58:04.123416Z","end":"2026-04-20T05:58:04.25426Z","steps":["trace[196441040] 'range keys from in-memory index tree' (duration: 130.474026ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:58:04.420078Z","caller":"traceutil/trace.go:171","msg":"trace[668561449] linearizableReadLoop","detail":"{readStateIndex:5288; appliedIndex:5286; }","duration":"124.102122ms","start":"2026-04-20T05:58:04.295922Z","end":"2026-04-20T05:58:04.420024Z","steps":["trace[668561449] 'read index received' (duration: 3.576787ms)","trace[668561449] 'applied index is now lower than readState.Index' (duration: 120.523875ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:58:04.420196Z","caller":"traceutil/trace.go:171","msg":"trace[387393630] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"161.559209ms","start":"2026-04-20T05:58:04.258571Z","end":"2026-04-20T05:58:04.42013Z","steps":["trace[387393630] 'process raft request' (duration: 96.911123ms)","trace[387393630] 'compare' (duration: 64.264588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:58:04.420459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.50222ms","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-04-20T05:58:04.42058Z","caller":"traceutil/trace.go:171","msg":"trace[1532966406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5101; }","duration":"124.680854ms","start":"2026-04-20T05:58:04.295871Z","end":"2026-04-20T05:58:04.420552Z","steps":["trace[1532966406] 'agreement among raft nodes before linearized reading' (duration: 124.287445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:15.091766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.387678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:58:15.0923Z","caller":"traceutil/trace.go:171","msg":"trace[27668578] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"221.32635ms","start":"2026-04-20T05:58:14.870951Z","end":"2026-04-20T05:58:15.092278Z","steps":["trace[27668578] 'process raft request' (duration: 118.314379ms)","trace[27668578] 'compare' (duration: 102.117893ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:58:15.111882Z","caller":"traceutil/trace.go:171","msg":"trace[1576879129] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"240.833516ms","start":"2026-04-20T05:58:14.87102Z","end":"2026-04-20T05:58:15.111853Z","steps":["trace[1576879129] 'process raft request' (duration: 240.649402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:58:15.112172Z","caller":"traceutil/trace.go:171","msg":"trace[1606846684] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"240.884115ms","start":"2026-04-20T05:58:14.871276Z","end":"2026-04-20T05:58:15.11216Z","steps":["trace[1606846684] 'process raft request' (duration: 240.517078ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:58:15.11226Z","caller":"traceutil/trace.go:171","msg":"trace[1554897181] linearizableReadLoop","detail":"{readStateIndex:5459; appliedIndex:5455; }","duration":"239.802733ms","start":"2026-04-20T05:58:14.872414Z","end":"2026-04-20T05:58:15.112216Z","steps":["trace[1554897181] 'read index received' (duration: 116.862767ms)","trace[1554897181] 'applied index is now lower than readState.Index' (duration: 122.938806ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:58:15.112366Z","caller":"traceutil/trace.go:171","msg":"trace[287429208] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"240.168461ms","start":"2026-04-20T05:58:14.872176Z","end":"2026-04-20T05:58:15.112344Z","steps":["trace[287429208] 'process raft request' (duration: 239.899014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:15.112462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.012109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:58:15.112508Z","caller":"traceutil/trace.go:171","msg":"trace[1382120852] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5268; }","duration":"240.11586ms","start":"2026-04-20T05:58:14.872379Z","end":"2026-04-20T05:58:15.112494Z","steps":["trace[1382120852] 'agreement among raft nodes before linearized reading' (duration: 240.004648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:15.112529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.23714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T05:58:15.112571Z","caller":"traceutil/trace.go:171","msg":"trace[1972637319] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5268; }","duration":"222.310642ms","start":"2026-04-20T05:58:14.890246Z","end":"2026-04-20T05:58:15.112556Z","steps":["trace[1972637319] 'agreement among raft nodes before linearized reading' (duration: 222.171439ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:58:51.565106Z","caller":"traceutil/trace.go:171","msg":"trace[1285515878] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"118.059702ms","start":"2026-04-20T05:58:51.447007Z","end":"2026-04-20T05:58:51.565067Z","steps":["trace[1285515878] 'process raft request' (duration: 117.844688ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:41.464062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2843} {"level":"info","ts":"2026-04-20T05:59:41.530133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2843,"took":"64.896712ms","hash":4237361545,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T05:59:41.530193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4237361545,"revision":2843,"compact-revision":-1} {"level":"info","ts":"2026-04-20T06:04:01.970174Z","caller":"traceutil/trace.go:171","msg":"trace[1895649695] transaction","detail":"{read_only:false; response_revision:7897; number_of_response:1; }","duration":"200.694836ms","start":"2026-04-20T06:04:01.769455Z","end":"2026-04-20T06:04:01.97015Z","steps":["trace[1895649695] 'process raft request' (duration: 200.562753ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:04:02.147169Z","caller":"traceutil/trace.go:171","msg":"trace[1423572545] linearizableReadLoop","detail":"{readStateIndex:8174; appliedIndex:8171; }","duration":"264.220249ms","start":"2026-04-20T06:04:01.882925Z","end":"2026-04-20T06:04:02.147145Z","steps":["trace[1423572545] 'read index received' (duration: 87.093795ms)","trace[1423572545] 'applied index is now lower than readState.Index' (duration: 177.125573ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:04:02.147203Z","caller":"traceutil/trace.go:171","msg":"trace[2101580711] transaction","detail":"{read_only:false; response_revision:7898; number_of_response:1; }","duration":"377.133865ms","start":"2026-04-20T06:04:01.770036Z","end":"2026-04-20T06:04:02.14717Z","steps":["trace[2101580711] 'process raft request' (duration: 376.851839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:04:02.147356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:04:01.770015Z","time spent":"377.263587ms","remote":"127.0.0.1:44130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:04:02.147438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.341333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T06:04:02.147481Z","caller":"traceutil/trace.go:171","msg":"trace[1234812503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7899; }","duration":"119.399074ms","start":"2026-04-20T06:04:02.028071Z","end":"2026-04-20T06:04:02.14747Z","steps":["trace[1234812503] 'agreement among raft nodes before linearized reading' (duration: 119.21082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:04:02.147365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.414182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T06:04:02.147537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.544198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T06:04:02.147255Z","caller":"traceutil/trace.go:171","msg":"trace[1201800366] transaction","detail":"{read_only:false; response_revision:7899; number_of_response:1; }","duration":"367.041419ms","start":"2026-04-20T06:04:01.780179Z","end":"2026-04-20T06:04:02.14722Z","steps":["trace[1201800366] 'process raft request' (duration: 366.909236ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:04:02.147612Z","caller":"traceutil/trace.go:171","msg":"trace[1778214018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7899; }","duration":"113.671511ms","start":"2026-04-20T06:04:02.033925Z","end":"2026-04-20T06:04:02.147596Z","steps":["trace[1778214018] 'agreement among raft nodes before linearized reading' (duration: 113.465967ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:04:02.14756Z","caller":"traceutil/trace.go:171","msg":"trace[1190210536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:7899; }","duration":"264.649607ms","start":"2026-04-20T06:04:01.882867Z","end":"2026-04-20T06:04:02.147517Z","steps":["trace[1190210536] 'agreement among raft nodes before linearized reading' (duration: 264.408932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:04:02.148089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:04:01.780163Z","time spent":"367.447168ms","remote":"127.0.0.1:44130","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-04-20T06:04:02.881113Z","caller":"traceutil/trace.go:171","msg":"trace[1771968035] transaction","detail":"{read_only:false; response_revision:7904; number_of_response:1; }","duration":"242.69215ms","start":"2026-04-20T06:04:02.638385Z","end":"2026-04-20T06:04:02.881077Z","steps":["trace[1771968035] 'process raft request' (duration: 242.528327ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:04:03.569635Z","caller":"traceutil/trace.go:171","msg":"trace[1760208952] linearizableReadLoop","detail":"{readStateIndex:8180; appliedIndex:8179; }","duration":"113.715853ms","start":"2026-04-20T06:04:03.45589Z","end":"2026-04-20T06:04:03.569606Z","steps":["trace[1760208952] 'read index received' (duration: 113.57352ms)","trace[1760208952] 'applied index is now lower than readState.Index' (duration: 140.983µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:04:03.569766Z","caller":"traceutil/trace.go:171","msg":"trace[642343617] transaction","detail":"{read_only:false; response_revision:7905; number_of_response:1; }","duration":"197.63056ms","start":"2026-04-20T06:04:03.372084Z","end":"2026-04-20T06:04:03.569714Z","steps":["trace[642343617] 'process raft request' (duration: 197.341404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:04:03.569876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.966628ms","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-04-20T06:04:03.569919Z","caller":"traceutil/trace.go:171","msg":"trace[1389688887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7905; }","duration":"114.07345ms","start":"2026-04-20T06:04:03.455832Z","end":"2026-04-20T06:04:03.569905Z","steps":["trace[1389688887] 'agreement among raft nodes before linearized reading' (duration: 113.880806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:04:29.389286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.71537ms","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-04-20T06:04:29.389372Z","caller":"traceutil/trace.go:171","msg":"trace[822388813] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8100; }","duration":"238.847083ms","start":"2026-04-20T06:04:29.150507Z","end":"2026-04-20T06:04:29.389354Z","steps":["trace[822388813] 'range keys from in-memory index tree' (duration: 238.465496ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:04:41.491522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5992} {"level":"info","ts":"2026-04-20T06:04:41.585699Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5992,"took":"92.215036ms","hash":2704784050,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":22470656,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T06:04:41.585758Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2704784050,"revision":5992,"compact-revision":2843} {"level":"warn","ts":"2026-04-20T06:04:42.744505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.54666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:04:42.744604Z","caller":"traceutil/trace.go:171","msg":"trace[1452404087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8216; }","duration":"127.720834ms","start":"2026-04-20T06:04:42.616862Z","end":"2026-04-20T06:04:42.744583Z","steps":["trace[1452404087] 'range keys from in-memory index tree' (duration: 127.435437ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:05:55.929678Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000240b.wal"} {"level":"info","ts":"2026-04-20T06:07:25.145579Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T06:07:25.150112Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T06:07:25.150254Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T06:09:41.502379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8194} {"level":"info","ts":"2026-04-20T06:09:41.580091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8194,"took":"75.929769ms","hash":2787939886,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":24748032,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T06:09:41.580161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2787939886,"revision":8194,"compact-revision":5992} {"level":"info","ts":"2026-04-20T06:14:41.511236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10547} {"level":"info","ts":"2026-04-20T06:14:41.594016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10547,"took":"80.960756ms","hash":944455506,"current-db-size-bytes":58372096,"current-db-size":"58 MB","current-db-size-in-use-bytes":19599360,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T06:14:41.594086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":944455506,"revision":10547,"compact-revision":8194}