{"level":"warn","ts":"2026-05-11T05:34:16.336442Z","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-11T05:34:16.336598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-11T05:34:16.336699Z","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-11T05:34:16.336711Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-11T05:34:16.336739Z","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-11T05:34:16.338404Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-11T05:34:16.338518Z","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.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-11T05:34:16.353458Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.677968ms"} {"level":"info","ts":"2026-05-11T05:34:16.828563Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-11T05:34:16.828697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T05:34:16.828726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-11T05:34:16.828742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T05:34:16.828752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-11T05:34:16.828798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-11T05:34:16.832402Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T05:34:16.8412Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T05:34:16.842935Z","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-11T05:34:16.847303Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T05:34:16.847688Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T05:34:16.847747Z","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-11T05:34:16.847947Z","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-11T05:34:16.847977Z","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-11T05:34:16.849407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-11T05:34:16.849705Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-11T05:34:16.853875Z","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-11T05:34:16.853964Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-11T05:34:16.854002Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-11T05:34:16.854413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T05:34:16.854509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T05:34:17.034176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T05:34:17.03423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T05:34:17.034256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-11T05:34:17.03427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-11T05:34:17.034276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-11T05:34:17.03429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-11T05:34:17.034298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-11T05:34:17.035523Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:34:17.036639Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T05:34:17.036665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:34:17.036738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T05:34:17.036984Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T05:34:17.037133Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T05:34:17.038874Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:34:17.039018Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:34:17.039067Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T05:34:17.041501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T05:34:17.042218Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"warn","ts":"2026-05-11T05:34:22.313448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410315565137983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T05:34:22.318443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.117622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-11T05:34:22.318507Z","caller":"traceutil/trace.go:171","msg":"trace[408119174] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:236; }","duration":"506.238184ms","start":"2026-05-11T05:34:21.812249Z","end":"2026-05-11T05:34:22.318488Z","steps":["trace[408119174] 'range keys from in-memory index tree' (duration: 506.027139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:22.318553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.81223Z","time spent":"506.314177ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-11T05:34:22.318856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.305554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-11T05:34:22.318882Z","caller":"traceutil/trace.go:171","msg":"trace[331294860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:236; }","duration":"560.358555ms","start":"2026-05-11T05:34:21.758516Z","end":"2026-05-11T05:34:22.318874Z","steps":["trace[331294860] 'range keys from in-memory index tree' (duration: 560.237611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:22.318896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.758502Z","time spent":"560.389957ms","remote":"127.0.0.1:51130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-05-11T05:34:22.31883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.211554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:22.319005Z","caller":"traceutil/trace.go:171","msg":"trace[2137224703] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:236; }","duration":"580.447701ms","start":"2026-05-11T05:34:21.73851Z","end":"2026-05-11T05:34:22.318958Z","steps":["trace[2137224703] 'range keys from in-memory index tree' (duration: 580.133931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:22.319082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.73849Z","time spent":"580.571144ms","remote":"127.0.0.1:51022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"info","ts":"2026-05-11T05:34:22.453874Z","caller":"traceutil/trace.go:171","msg":"trace[1218107527] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"641.293622ms","start":"2026-05-11T05:34:21.812559Z","end":"2026-05-11T05:34:22.453853Z","steps":["trace[1218107527] 'process raft request' (duration: 640.586632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:22.455533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.812547Z","time spent":"642.5927ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:23.488696Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035805889s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-11T05:34:24.164334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.010434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-11T05:34:24.16462Z","caller":"traceutil/trace.go:171","msg":"trace[1496688692] transaction","detail":"{read_only:false; number_of_response:0; response_revision:237; }","duration":"2.236455919s","start":"2026-05-11T05:34:21.92812Z","end":"2026-05-11T05:34:24.164576Z","steps":["trace[1496688692] 'process raft request' (duration: 1.561764585s)","trace[1496688692] 'compare' (duration: 673.755117ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:24.164884Z","caller":"traceutil/trace.go:171","msg":"trace[76095128] transaction","detail":"{read_only:false; number_of_response:0; response_revision:238; }","duration":"2.23313796s","start":"2026-05-11T05:34:21.931718Z","end":"2026-05-11T05:34:24.164856Z","steps":["trace[76095128] 'process raft request' (duration: 2.232937805s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.164848Z","caller":"traceutil/trace.go:171","msg":"trace[106790690] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"2.233207153s","start":"2026-05-11T05:34:21.931566Z","end":"2026-05-11T05:34:24.164774Z","steps":["trace[106790690] 'process raft request' (duration: 2.232909065s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.16499Z","caller":"traceutil/trace.go:171","msg":"trace[1375393729] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"1.691673427s","start":"2026-05-11T05:34:22.473294Z","end":"2026-05-11T05:34:24.164967Z","steps":["trace[1375393729] 'process raft request' (duration: 1.691627266s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.165016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.931693Z","time spent":"2.233251645s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:24.165021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.928088Z","time spent":"2.236627872s","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:24.165062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.931542Z","time spent":"2.233423669s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:24.165075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:22.473264Z","time spent":"1.691779101s","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:24.169773Z","caller":"traceutil/trace.go:171","msg":"trace[513166685] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"2.233053388s","start":"2026-05-11T05:34:21.933487Z","end":"2026-05-11T05:34:24.166541Z","steps":["trace[513166685] 'process raft request' (duration: 2.231350909s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.169858Z","caller":"traceutil/trace.go:171","msg":"trace[530629474] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"1.846281698s","start":"2026-05-11T05:34:22.323564Z","end":"2026-05-11T05:34:24.169846Z","steps":["trace[530629474] 'process raft request' (duration: 1.841307627s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.169908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:22.323531Z","time spent":"1.846356s","remote":"127.0.0.1:51022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:24.169979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.93348Z","time spent":"2.236398202s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:24.170177Z","caller":"traceutil/trace.go:171","msg":"trace[1427882082] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"2.236783454s","start":"2026-05-11T05:34:21.933384Z","end":"2026-05-11T05:34:24.170168Z","steps":["trace[1427882082] 'process raft request' (duration: 2.231412981s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.170183Z","caller":"traceutil/trace.go:171","msg":"trace[312236948] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"2.237869646s","start":"2026-05-11T05:34:21.932299Z","end":"2026-05-11T05:34:24.170168Z","steps":["trace[312236948] 'process raft request' (duration: 2.23239839s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.170224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.933336Z","time spent":"2.236862295s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:24.170251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.932293Z","time spent":"2.237927637s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:24.170327Z","caller":"traceutil/trace.go:171","msg":"trace[1624448956] linearizableReadLoop","detail":"{readStateIndex:249; appliedIndex:242; }","duration":"2.356908648s","start":"2026-05-11T05:34:21.813401Z","end":"2026-05-11T05:34:24.17031Z","steps":["trace[1624448956] 'read index received' (duration: 639.514599ms)","trace[1624448956] 'applied index is now lower than readState.Index' (duration: 1.717389989s)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:34:24.172751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.989734ms","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-11T05:34:24.172892Z","caller":"traceutil/trace.go:171","msg":"trace[1026466053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:242; }","duration":"950.153048ms","start":"2026-05-11T05:34:23.222716Z","end":"2026-05-11T05:34:24.172869Z","steps":["trace[1026466053] 'agreement among raft nodes before linearized reading' (duration: 949.278763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.173068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:23.222638Z","time spent":"950.297663ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:34:24.174601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.838176ms","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-05-11T05:34:24.174639Z","caller":"traceutil/trace.go:171","msg":"trace[1368330428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:242; }","duration":"508.912448ms","start":"2026-05-11T05:34:23.665708Z","end":"2026-05-11T05:34:24.17462Z","steps":["trace[1368330428] 'agreement among raft nodes before linearized reading' (duration: 504.78077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.174662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:23.665685Z","time spent":"508.97109ms","remote":"127.0.0.1:51194","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-05-11T05:34:24.175987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.36256205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:24.176284Z","caller":"traceutil/trace.go:171","msg":"trace[699289140] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:242; }","duration":"2.362760995s","start":"2026-05-11T05:34:21.813388Z","end":"2026-05-11T05:34:24.176149Z","steps":["trace[699289140] 'agreement among raft nodes before linearized reading' (duration: 2.357042713s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.176649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.81335Z","time spent":"2.363285231s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-11T05:34:24.177295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853787361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"warn","ts":"2026-05-11T05:34:24.177321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.285449377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:24.177358Z","caller":"traceutil/trace.go:171","msg":"trace[751895223] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:242; }","duration":"1.853882054s","start":"2026-05-11T05:34:22.323459Z","end":"2026-05-11T05:34:24.177341Z","steps":["trace[751895223] 'agreement among raft nodes before linearized reading' (duration: 1.853631387s)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.177375Z","caller":"traceutil/trace.go:171","msg":"trace[1522735819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:242; }","duration":"2.285491459s","start":"2026-05-11T05:34:21.891864Z","end":"2026-05-11T05:34:24.177355Z","steps":["trace[1522735819] 'agreement among raft nodes before linearized reading' (duration: 2.278587722s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.177402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:21.891789Z","time spent":"2.285606552s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T05:34:24.17741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:22.323449Z","time spent":"1.853947436s","remote":"127.0.0.1:51130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-05-11T05:34:24.179495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857414115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-11T05:34:24.179545Z","caller":"traceutil/trace.go:171","msg":"trace[1479463879] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:242; }","duration":"1.857515727s","start":"2026-05-11T05:34:22.322014Z","end":"2026-05-11T05:34:24.179529Z","steps":["trace[1479463879] 'agreement among raft nodes before linearized reading' (duration: 1.855972334s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.179574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:22.321981Z","time spent":"1.857587399s","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-11T05:34:24.179735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533384051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:24.180206Z","caller":"traceutil/trace.go:171","msg":"trace[1727490657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:242; }","duration":"1.533909336s","start":"2026-05-11T05:34:22.646281Z","end":"2026-05-11T05:34:24.18019Z","steps":["trace[1727490657] 'agreement among raft nodes before linearized reading' (duration: 1.524222871s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.180248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:22.646261Z","time spent":"1.533976558s","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-11T05:34:24.357289Z","caller":"traceutil/trace.go:171","msg":"trace[831957010] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"177.107093ms","start":"2026-05-11T05:34:24.18014Z","end":"2026-05-11T05:34:24.357247Z","steps":["trace[831957010] 'read index received' (duration: 122.087389ms)","trace[831957010] 'applied index is now lower than readState.Index' (duration: 55.017984ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:24.357358Z","caller":"traceutil/trace.go:171","msg":"trace[1657995029] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"177.263348ms","start":"2026-05-11T05:34:24.180053Z","end":"2026-05-11T05:34:24.357316Z","steps":["trace[1657995029] 'process raft request' (duration: 122.157081ms)","trace[1657995029] 'compare' (duration: 54.86549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:34:24.357848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.677809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-11T05:34:24.357984Z","caller":"traceutil/trace.go:171","msg":"trace[1545653601] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:243; }","duration":"177.978068ms","start":"2026-05-11T05:34:24.179978Z","end":"2026-05-11T05:34:24.357956Z","steps":["trace[1545653601] 'agreement among raft nodes before linearized reading' (duration: 177.515934ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.358648Z","caller":"traceutil/trace.go:171","msg":"trace[800991945] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"180.600152ms","start":"2026-05-11T05:34:24.178031Z","end":"2026-05-11T05:34:24.358631Z","steps":["trace[800991945] 'process raft request' (duration: 180.383956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.35891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.289426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-11T05:34:24.359019Z","caller":"traceutil/trace.go:171","msg":"trace[541425452] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:248; }","duration":"178.466702ms","start":"2026-05-11T05:34:24.180527Z","end":"2026-05-11T05:34:24.358993Z","steps":["trace[541425452] 'agreement among raft nodes before linearized reading' (duration: 178.259675ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.359003Z","caller":"traceutil/trace.go:171","msg":"trace[1662745499] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"174.553991ms","start":"2026-05-11T05:34:24.184424Z","end":"2026-05-11T05:34:24.358978Z","steps":["trace[1662745499] 'process raft request' (duration: 174.322224ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.359315Z","caller":"traceutil/trace.go:171","msg":"trace[1738147673] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"178.844402ms","start":"2026-05-11T05:34:24.180456Z","end":"2026-05-11T05:34:24.3593Z","steps":["trace[1738147673] 'process raft request' (duration: 178.090421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.359577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.57623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-11T05:34:24.35963Z","caller":"traceutil/trace.go:171","msg":"trace[1806486794] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:248; }","duration":"174.642073ms","start":"2026-05-11T05:34:24.184974Z","end":"2026-05-11T05:34:24.359616Z","steps":["trace[1806486794] 'agreement among raft nodes before linearized reading' (duration: 174.54568ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.359641Z","caller":"traceutil/trace.go:171","msg":"trace[1125906436] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"177.516214ms","start":"2026-05-11T05:34:24.182068Z","end":"2026-05-11T05:34:24.359584Z","steps":["trace[1125906436] 'process raft request' (duration: 176.545747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.359809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.799148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:24.359853Z","caller":"traceutil/trace.go:171","msg":"trace[62128489] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:248; }","duration":"167.873881ms","start":"2026-05-11T05:34:24.191965Z","end":"2026-05-11T05:34:24.359839Z","steps":["trace[62128489] 'agreement among raft nodes before linearized reading' (duration: 167.812089ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.359923Z","caller":"traceutil/trace.go:171","msg":"trace[997846408] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"177.088753ms","start":"2026-05-11T05:34:24.182793Z","end":"2026-05-11T05:34:24.359882Z","steps":["trace[997846408] 'process raft request' (duration: 175.922329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.360014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.746989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:24.360053Z","caller":"traceutil/trace.go:171","msg":"trace[1141839984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:248; }","duration":"172.7976ms","start":"2026-05-11T05:34:24.187246Z","end":"2026-05-11T05:34:24.360043Z","steps":["trace[1141839984] 'agreement among raft nodes before linearized reading' (duration: 172.732398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.360228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.353118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-11T05:34:24.360289Z","caller":"traceutil/trace.go:171","msg":"trace[654719163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:248; }","duration":"178.43404ms","start":"2026-05-11T05:34:24.18184Z","end":"2026-05-11T05:34:24.360274Z","steps":["trace[654719163] 'agreement among raft nodes before linearized reading' (duration: 177.73676ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.560743Z","caller":"traceutil/trace.go:171","msg":"trace[1797169794] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"127.082232ms","start":"2026-05-11T05:34:24.433634Z","end":"2026-05-11T05:34:24.560716Z","steps":["trace[1797169794] 'process raft request' (duration: 127.01626ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.560815Z","caller":"traceutil/trace.go:171","msg":"trace[1814234170] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"128.321037ms","start":"2026-05-11T05:34:24.432452Z","end":"2026-05-11T05:34:24.560773Z","steps":["trace[1814234170] 'read index received' (duration: 103.443309ms)","trace[1814234170] 'applied index is now lower than readState.Index' (duration: 24.872617ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:24.560801Z","caller":"traceutil/trace.go:171","msg":"trace[1243800718] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"134.650617ms","start":"2026-05-11T05:34:24.426102Z","end":"2026-05-11T05:34:24.560753Z","steps":["trace[1243800718] 'process raft request' (duration: 109.849942ms)","trace[1243800718] 'compare' (duration: 24.523417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:34:24.561089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.974431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-11T05:34:24.561433Z","caller":"traceutil/trace.go:171","msg":"trace[1289056577] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:263; }","duration":"138.359072ms","start":"2026-05-11T05:34:24.423026Z","end":"2026-05-11T05:34:24.561385Z","steps":["trace[1289056577] 'agreement among raft nodes before linearized reading' (duration: 137.94765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.561124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.112275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:34:24.561607Z","caller":"traceutil/trace.go:171","msg":"trace[1810920422] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:263; }","duration":"125.655191ms","start":"2026-05-11T05:34:24.435912Z","end":"2026-05-11T05:34:24.561567Z","steps":["trace[1810920422] 'agreement among raft nodes before linearized reading' (duration: 125.111845ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:24.738429Z","caller":"traceutil/trace.go:171","msg":"trace[636801310] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"113.64923ms","start":"2026-05-11T05:34:24.624747Z","end":"2026-05-11T05:34:24.738396Z","steps":["trace[636801310] 'read index received' (duration: 96.203215ms)","trace[636801310] 'applied index is now lower than readState.Index' (duration: 17.443025ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:24.738444Z","caller":"traceutil/trace.go:171","msg":"trace[39089126] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"124.661213ms","start":"2026-05-11T05:34:24.613731Z","end":"2026-05-11T05:34:24.738392Z","steps":["trace[39089126] 'process raft request' (duration: 107.265379ms)","trace[39089126] 'compare' (duration: 17.082565ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:24.738649Z","caller":"traceutil/trace.go:171","msg":"trace[299049211] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"113.173786ms","start":"2026-05-11T05:34:24.62541Z","end":"2026-05-11T05:34:24.738584Z","steps":["trace[299049211] 'process raft request' (duration: 112.904858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:24.738726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.941268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-11T05:34:24.739348Z","caller":"traceutil/trace.go:171","msg":"trace[1823470858] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"114.623018ms","start":"2026-05-11T05:34:24.624708Z","end":"2026-05-11T05:34:24.739331Z","steps":["trace[1823470858] 'agreement among raft nodes before linearized reading' (duration: 113.837105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:27.54531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.462354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T05:34:27.545422Z","caller":"traceutil/trace.go:171","msg":"trace[1549351251] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"158.395315ms","start":"2026-05-11T05:34:27.387008Z","end":"2026-05-11T05:34:27.545403Z","steps":["trace[1549351251] 'read index received' (duration: 40.501µs)","trace[1549351251] 'applied index is now lower than readState.Index' (duration: 158.353654ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:27.545618Z","caller":"traceutil/trace.go:171","msg":"trace[1836486181] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"220.528984ms","start":"2026-05-11T05:34:27.325077Z","end":"2026-05-11T05:34:27.545606Z","steps":["trace[1836486181] 'process raft request' (duration: 30.670319ms)","trace[1836486181] 'compare' (duration: 189.243328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:34:27.545752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.329254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-11T05:34:27.545887Z","caller":"traceutil/trace.go:171","msg":"trace[1954187597] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:328; }","duration":"127.524311ms","start":"2026-05-11T05:34:27.418338Z","end":"2026-05-11T05:34:27.545862Z","steps":["trace[1954187597] 'agreement among raft nodes before linearized reading' (duration: 127.218752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:27.546361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.306629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-11T05:34:27.546478Z","caller":"traceutil/trace.go:171","msg":"trace[1040629053] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:328; }","duration":"159.489434ms","start":"2026-05-11T05:34:27.386964Z","end":"2026-05-11T05:34:27.546454Z","steps":["trace[1040629053] 'agreement among raft nodes before linearized reading' (duration: 159.225217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:30.355473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.91778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T05:34:30.355577Z","caller":"traceutil/trace.go:171","msg":"trace[829358918] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:360; }","duration":"155.095184ms","start":"2026-05-11T05:34:30.200458Z","end":"2026-05-11T05:34:30.355553Z","steps":["trace[829358918] 'range keys from in-memory index tree' (duration: 154.762254ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:34.676813Z","caller":"traceutil/trace.go:171","msg":"trace[1463284918] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"126.954762ms","start":"2026-05-11T05:34:34.549825Z","end":"2026-05-11T05:34:34.67678Z","steps":["trace[1463284918] 'process raft request' (duration: 126.770128ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:34.678422Z","caller":"traceutil/trace.go:171","msg":"trace[1764905828] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"124.789026ms","start":"2026-05-11T05:34:34.553621Z","end":"2026-05-11T05:34:34.67841Z","steps":["trace[1764905828] 'process raft request' (duration: 124.336404ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:52.942054Z","caller":"traceutil/trace.go:171","msg":"trace[1461612011] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"458.476507ms","start":"2026-05-11T05:34:52.483551Z","end":"2026-05-11T05:34:52.942028Z","steps":["trace[1461612011] 'process raft request' (duration: 458.249712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.94223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.48353Z","time spent":"458.629321ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.94971Z","caller":"traceutil/trace.go:171","msg":"trace[1913515568] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"456.495113ms","start":"2026-05-11T05:34:52.493169Z","end":"2026-05-11T05:34:52.949665Z","steps":["trace[1913515568] 'process raft request' (duration: 456.131074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.94993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.49315Z","time spent":"456.705277ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.949934Z","caller":"traceutil/trace.go:171","msg":"trace[1937460332] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"447.093828ms","start":"2026-05-11T05:34:52.502817Z","end":"2026-05-11T05:34:52.94991Z","steps":["trace[1937460332] 'process raft request' (duration: 446.884044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.950159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.502797Z","time spent":"447.239471ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.950735Z","caller":"traceutil/trace.go:171","msg":"trace[951285995] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"437.548491ms","start":"2026-05-11T05:34:52.513172Z","end":"2026-05-11T05:34:52.950721Z","steps":["trace[951285995] 'process raft request' (duration: 436.59322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.950885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.51315Z","time spent":"437.626405ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.951137Z","caller":"traceutil/trace.go:171","msg":"trace[517292638] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"432.527928ms","start":"2026-05-11T05:34:52.518597Z","end":"2026-05-11T05:34:52.951125Z","steps":["trace[517292638] 'process raft request' (duration: 431.992066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.951196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.518582Z","time spent":"432.58855ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.951862Z","caller":"traceutil/trace.go:171","msg":"trace[758569114] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"433.140512ms","start":"2026-05-11T05:34:52.518664Z","end":"2026-05-11T05:34:52.951804Z","steps":["trace[758569114] 'process raft request' (duration: 432.384575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.952089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.51866Z","time spent":"433.319906ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.952241Z","caller":"traceutil/trace.go:171","msg":"trace[896300039] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"425.671552ms","start":"2026-05-11T05:34:52.526555Z","end":"2026-05-11T05:34:52.952227Z","steps":["trace[896300039] 'process raft request' (duration: 425.12292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.952308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.526541Z","time spent":"425.739793ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.952358Z","caller":"traceutil/trace.go:171","msg":"trace[162381727] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"423.986945ms","start":"2026-05-11T05:34:52.528351Z","end":"2026-05-11T05:34:52.952337Z","steps":["trace[162381727] 'process raft request' (duration: 423.383771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.952478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.528343Z","time spent":"424.082027ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.953101Z","caller":"traceutil/trace.go:171","msg":"trace[212487941] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"412.097314ms","start":"2026-05-11T05:34:52.54099Z","end":"2026-05-11T05:34:52.953087Z","steps":["trace[212487941] 'process raft request' (duration: 410.813534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.9532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.540963Z","time spent":"412.176536ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.953448Z","caller":"traceutil/trace.go:171","msg":"trace[2043287194] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"411.641523ms","start":"2026-05-11T05:34:52.541795Z","end":"2026-05-11T05:34:52.953437Z","steps":["trace[2043287194] 'process raft request' (duration: 410.063308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.953532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.541776Z","time spent":"411.703405ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T05:34:52.953791Z","caller":"traceutil/trace.go:171","msg":"trace[1685843490] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"293.205992ms","start":"2026-05-11T05:34:52.660574Z","end":"2026-05-11T05:34:52.953779Z","steps":["trace[1685843490] 'process raft request' (duration: 292.861494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:34:52.95384Z","caller":"traceutil/trace.go:171","msg":"trace[1606287986] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:494; }","duration":"173.531473ms","start":"2026-05-11T05:34:52.780294Z","end":"2026-05-11T05:34:52.953826Z","steps":["trace[1606287986] 'read index received' (duration: 161.563241ms)","trace[1606287986] 'applied index is now lower than readState.Index' (duration: 11.967162ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:34:52.953989Z","caller":"traceutil/trace.go:171","msg":"trace[10461871] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"393.637843ms","start":"2026-05-11T05:34:52.560337Z","end":"2026-05-11T05:34:52.953975Z","steps":["trace[10461871] 'process raft request' (duration: 392.861596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.954076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:34:52.560305Z","time spent":"393.744666ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:34:52.9544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.093996ms","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-05-11T05:34:52.954446Z","caller":"traceutil/trace.go:171","msg":"trace[840034795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"174.151257ms","start":"2026-05-11T05:34:52.780283Z","end":"2026-05-11T05:34:52.954434Z","steps":["trace[840034795] 'agreement among raft nodes before linearized reading' (duration: 173.87581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.963884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.60008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T05:34:52.963958Z","caller":"traceutil/trace.go:171","msg":"trace[586682627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"175.733913ms","start":"2026-05-11T05:34:52.788211Z","end":"2026-05-11T05:34:52.963945Z","steps":["trace[586682627] 'agreement among raft nodes before linearized reading' (duration: 175.585819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.964167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.135365ms","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-11T05:34:52.964181Z","caller":"traceutil/trace.go:171","msg":"trace[174396195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"170.172197ms","start":"2026-05-11T05:34:52.794004Z","end":"2026-05-11T05:34:52.964177Z","steps":["trace[174396195] 'agreement among raft nodes before linearized reading' (duration: 170.102565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:52.964251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.691632ms","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-11T05:34:52.964263Z","caller":"traceutil/trace.go:171","msg":"trace[1899208982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"175.705722ms","start":"2026-05-11T05:34:52.788553Z","end":"2026-05-11T05:34:52.964259Z","steps":["trace[1899208982] 'agreement among raft nodes before linearized reading' (duration: 175.679372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:34:55.960303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.139331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T05:35:00.499115Z","caller":"traceutil/trace.go:171","msg":"trace[147710384] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"449.71578ms","start":"2026-05-11T05:35:00.049374Z","end":"2026-05-11T05:35:00.49909Z","steps":["trace[147710384] 'process raft request' (duration: 449.546647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:00.499613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:00.049348Z","time spent":"449.891995ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:35:01.048581Z","caller":"traceutil/trace.go:171","msg":"trace[1442859619] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"846.059528ms","start":"2026-05-11T05:35:00.202492Z","end":"2026-05-11T05:35:01.048552Z","steps":["trace[1442859619] 'read index received' (duration: 296.603038ms)","trace[1442859619] 'applied index is now lower than readState.Index' (duration: 549.45528ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:01.048635Z","caller":"traceutil/trace.go:171","msg":"trace[2039090081] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"972.935116ms","start":"2026-05-11T05:35:00.075664Z","end":"2026-05-11T05:35:01.048599Z","steps":["trace[2039090081] 'process raft request' (duration: 971.121328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.049527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.885314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:01.049626Z","caller":"traceutil/trace.go:171","msg":"trace[1716898549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"792.763782ms","start":"2026-05-11T05:35:00.25684Z","end":"2026-05-11T05:35:01.049604Z","steps":["trace[1716898549] 'agreement among raft nodes before linearized reading' (duration: 791.889943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.049697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:00.25682Z","time spent":"792.856494ms","remote":"127.0.0.1:50954","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-11T05:35:01.049838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.301224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-11T05:35:01.049989Z","caller":"traceutil/trace.go:171","msg":"trace[1616226679] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:593; }","duration":"847.472758ms","start":"2026-05-11T05:35:00.202437Z","end":"2026-05-11T05:35:01.049909Z","steps":["trace[1616226679] 'agreement among raft nodes before linearized reading' (duration: 846.278702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.0501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:00.202412Z","time spent":"847.649661ms","remote":"127.0.0.1:50976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-05-11T05:35:01.051596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:00.075624Z","time spent":"973.108369ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T05:35:01.958195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.366093ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-11T05:35:01.958875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.056622Z","time spent":"902.243205ms","remote":"127.0.0.1:50976","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-11T05:35:01.959139Z","caller":"traceutil/trace.go:171","msg":"trace[1724806019] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"898.989845ms","start":"2026-05-11T05:35:01.060124Z","end":"2026-05-11T05:35:01.959114Z","steps":["trace[1724806019] 'process raft request' (duration: 898.572186ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:01.959251Z","caller":"traceutil/trace.go:171","msg":"trace[1322570221] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"889.44976ms","start":"2026-05-11T05:35:01.069763Z","end":"2026-05-11T05:35:01.959213Z","steps":["trace[1322570221] 'read index received' (duration: 114.2459ms)","trace[1322570221] 'applied index is now lower than readState.Index' (duration: 775.20109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:35:01.959272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.06011Z","time spent":"899.086107ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:35:01.959385Z","caller":"traceutil/trace.go:171","msg":"trace[2028440959] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"846.548904ms","start":"2026-05-11T05:35:01.1128Z","end":"2026-05-11T05:35:01.959349Z","steps":["trace[2028440959] 'process raft request' (duration: 846.165006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.959533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.11275Z","time spent":"846.707887ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T05:35:01.959561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.772838ms","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-11T05:35:01.959603Z","caller":"traceutil/trace.go:171","msg":"trace[1525074379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"889.865171ms","start":"2026-05-11T05:35:01.069724Z","end":"2026-05-11T05:35:01.959589Z","steps":["trace[1525074379] 'agreement among raft nodes before linearized reading' (duration: 889.627535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.959642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.069701Z","time spent":"889.933623ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T05:35:01.959709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.916677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T05:35:01.95975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.453137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:01.959797Z","caller":"traceutil/trace.go:171","msg":"trace[707585787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"768.559609ms","start":"2026-05-11T05:35:01.191224Z","end":"2026-05-11T05:35:01.959783Z","steps":["trace[707585787] 'agreement among raft nodes before linearized reading' (duration: 768.488067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.959835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.191187Z","time spent":"768.641541ms","remote":"127.0.0.1:50956","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-11T05:35:01.95984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.225303ms","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-11T05:35:01.959872Z","caller":"traceutil/trace.go:171","msg":"trace[104505248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"886.265663ms","start":"2026-05-11T05:35:01.073598Z","end":"2026-05-11T05:35:01.959864Z","steps":["trace[104505248] 'agreement among raft nodes before linearized reading' (duration: 886.183022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.959912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.07359Z","time spent":"886.315854ms","remote":"127.0.0.1:51194","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":"info","ts":"2026-05-11T05:35:01.959758Z","caller":"traceutil/trace.go:171","msg":"trace[455273706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"887.006399ms","start":"2026-05-11T05:35:01.072738Z","end":"2026-05-11T05:35:01.959745Z","steps":["trace[455273706] 'agreement among raft nodes before linearized reading' (duration: 886.851876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:01.959966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:01.072718Z","time spent":"887.230504ms","remote":"127.0.0.1:51194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T05:35:04.297884Z","caller":"traceutil/trace.go:171","msg":"trace[337650091] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"236.912005ms","start":"2026-05-11T05:35:04.060951Z","end":"2026-05-11T05:35:04.297862Z","steps":["trace[337650091] 'read index received' (duration: 159.652305ms)","trace[337650091] 'applied index is now lower than readState.Index' (duration: 77.25868ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:04.297927Z","caller":"traceutil/trace.go:171","msg":"trace[1334445761] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"291.310064ms","start":"2026-05-11T05:35:04.00659Z","end":"2026-05-11T05:35:04.297901Z","steps":["trace[1334445761] 'process raft request' (duration: 213.923603ms)","trace[1334445761] 'compare' (duration: 77.107855ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:04.297965Z","caller":"traceutil/trace.go:171","msg":"trace[839316605] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"290.586818ms","start":"2026-05-11T05:35:04.007346Z","end":"2026-05-11T05:35:04.297933Z","steps":["trace[839316605] 'process raft request' (duration: 290.458085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:04.29846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.459345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4vhgd\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-05-11T05:35:04.298585Z","caller":"traceutil/trace.go:171","msg":"trace[972600671] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4vhgd; range_end:; response_count:1; response_revision:617; }","duration":"237.65946ms","start":"2026-05-11T05:35:04.060895Z","end":"2026-05-11T05:35:04.298554Z","steps":["trace[972600671] 'agreement among raft nodes before linearized reading' (duration: 237.100578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:04.300835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.487756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:04.300828Z","caller":"traceutil/trace.go:171","msg":"trace[1881383771] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"239.549479ms","start":"2026-05-11T05:35:04.061223Z","end":"2026-05-11T05:35:04.300773Z","steps":["trace[1881383771] 'process raft request' (duration: 239.389634ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:04.3009Z","caller":"traceutil/trace.go:171","msg":"trace[941291542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"100.584768ms","start":"2026-05-11T05:35:04.200297Z","end":"2026-05-11T05:35:04.300882Z","steps":["trace[941291542] 'agreement among raft nodes before linearized reading' (duration: 100.448145ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:04.494536Z","caller":"traceutil/trace.go:171","msg":"trace[1213398417] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"180.502761ms","start":"2026-05-11T05:35:04.313994Z","end":"2026-05-11T05:35:04.494497Z","steps":["trace[1213398417] 'process raft request' (duration: 94.237164ms)","trace[1213398417] 'compare' (duration: 86.073413ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T05:35:15.042579Z","caller":"traceutil/trace.go:171","msg":"trace[974935690] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"192.236944ms","start":"2026-05-11T05:35:14.85031Z","end":"2026-05-11T05:35:15.042547Z","steps":["trace[974935690] 'process raft request' (duration: 191.917458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:17.692029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410315565139036,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T05:35:17.770483Z","caller":"traceutil/trace.go:171","msg":"trace[1174560455] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"578.54444ms","start":"2026-05-11T05:35:17.19191Z","end":"2026-05-11T05:35:17.770454Z","steps":["trace[1174560455] 'read index received' (duration: 578.310365ms)","trace[1174560455] 'applied index is now lower than readState.Index' (duration: 232.755µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T05:35:17.770617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.689304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T05:35:17.770663Z","caller":"traceutil/trace.go:171","msg":"trace[926944038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"578.798906ms","start":"2026-05-11T05:35:17.19185Z","end":"2026-05-11T05:35:17.770649Z","steps":["trace[926944038] 'agreement among raft nodes before linearized reading' (duration: 578.708344ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:35:17.770627Z","caller":"traceutil/trace.go:171","msg":"trace[1015602092] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"610.043441ms","start":"2026-05-11T05:35:17.160503Z","end":"2026-05-11T05:35:17.770546Z","steps":["trace[1015602092] 'process raft request' (duration: 609.811866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T05:35:17.770722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:17.191819Z","time spent":"578.891287ms","remote":"127.0.0.1:50954","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-11T05:35:17.770939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T05:35:17.160485Z","time spent":"610.274536ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T05:35:20.280056Z","caller":"traceutil/trace.go:171","msg":"trace[1586665570] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"103.90346ms","start":"2026-05-11T05:35:20.176104Z","end":"2026-05-11T05:35:20.280007Z","steps":["trace[1586665570] 'process raft request' (duration: 103.527243ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T05:37:11.198496Z","caller":"traceutil/trace.go:171","msg":"trace[686255715] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"195.975743ms","start":"2026-05-11T05:37:11.002496Z","end":"2026-05-11T05:37:11.198471Z","steps":["trace[686255715] 'process raft request' (duration: 154.627581ms)","trace[686255715] 'compare' (duration: 41.053039ms)"],"step_count":2}