{"level":"warn","ts":"2026-08-07T02:28:19.32524Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-07T02:28:19.325327Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.118:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.118:2380","--initial-cluster=instance=https://199.19.213.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.118: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-08-07T02:28:19.325399Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-07T02:28:19.325407Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-08-07T02:28:19.325422Z","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-08-07T02:28:19.326011Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"]} {"level":"info","ts":"2026-08-07T02:28:19.326077Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.118: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-08-07T02:28:19.329547Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.283364ms"} {"level":"info","ts":"2026-08-07T02:28:19.33455Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"511d8c552db01fa8","cluster-id":"8d545d29b73b98f3"} {"level":"info","ts":"2026-08-07T02:28:19.334624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=()"} {"level":"info","ts":"2026-08-07T02:28:19.33465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 0"} {"level":"info","ts":"2026-08-07T02:28:19.334666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 511d8c552db01fa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-07T02:28:19.334675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 1"} {"level":"info","ts":"2026-08-07T02:28:19.334713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"warn","ts":"2026-08-07T02:28:19.339491Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-07T02:28:19.341234Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-07T02:28:19.342459Z","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-08-07T02:28:19.345533Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"511d8c552db01fa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-07T02:28:19.345848Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"511d8c552db01fa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-07T02:28:19.346462Z","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-08-07T02:28:19.346695Z","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-08-07T02:28:19.34673Z","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-08-07T02:28:19.349256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"info","ts":"2026-08-07T02:28:19.3494Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","added-peer-id":"511d8c552db01fa8","added-peer-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-08-07T02:28:19.350515Z","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-08-07T02:28:19.350751Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"511d8c552db01fa8","initial-advertise-peer-urls":["https://199.19.213.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-07T02:28:19.350777Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-07T02:28:19.35083Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-08-07T02:28:19.35084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-08-07T02:28:20.035008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 is starting a new election at term 1"} {"level":"info","ts":"2026-08-07T02:28:20.035097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-07T02:28:20.035118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgPreVoteResp from 511d8c552db01fa8 at term 1"} {"level":"info","ts":"2026-08-07T02:28:20.035151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became candidate at term 2"} {"level":"info","ts":"2026-08-07T02:28:20.035159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgVoteResp from 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-08-07T02:28:20.035182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became leader at term 2"} {"level":"info","ts":"2026-08-07T02:28:20.035197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 511d8c552db01fa8 elected leader 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-08-07T02:28:20.102995Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T02:28:20.105526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T02:28:20.105691Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T02:28:20.105722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-07T02:28:20.105741Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-07T02:28:20.105766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-07T02:28:20.105742Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"511d8c552db01fa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.118:2379]}","request-path":"/0/members/511d8c552db01fa8/attributes","cluster-id":"8d545d29b73b98f3","publish-timeout":"7s"} {"level":"info","ts":"2026-08-07T02:28:20.106262Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-07T02:28:20.106401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-07T02:28:20.108718Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-07T02:28:20.109059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.118:2379"} {"level":"info","ts":"2026-08-07T02:28:25.774628Z","caller":"traceutil/trace.go:171","msg":"trace[3250812] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"538.648789ms","start":"2026-08-07T02:28:25.23593Z","end":"2026-08-07T02:28:25.774579Z","steps":["trace[3250812] 'process raft request' (duration: 538.517067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:25.775147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.235895Z","time spent":"538.841403ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T02:28:26.641879Z","caller":"traceutil/trace.go:171","msg":"trace[1066190383] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:267; }","duration":"1.291675502s","start":"2026-08-07T02:28:25.350176Z","end":"2026-08-07T02:28:26.641851Z","steps":["trace[1066190383] 'read index received' (duration: 424.345996ms)","trace[1066190383] 'applied index is now lower than readState.Index' (duration: 867.328326ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:28:26.64187Z","caller":"traceutil/trace.go:171","msg":"trace[787807987] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"1.384580477s","start":"2026-08-07T02:28:25.25723Z","end":"2026-08-07T02:28:26.64181Z","steps":["trace[787807987] 'process raft request' (duration: 1.384400284s)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:28:26.641925Z","caller":"traceutil/trace.go:171","msg":"trace[1057984974] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"972.817067ms","start":"2026-08-07T02:28:25.669045Z","end":"2026-08-07T02:28:26.641862Z","steps":["trace[1057984974] 'process raft request' (duration: 972.692235ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:28:26.642Z","caller":"traceutil/trace.go:171","msg":"trace[1620668411] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"971.952541ms","start":"2026-08-07T02:28:25.670038Z","end":"2026-08-07T02:28:26.64199Z","steps":["trace[1620668411] 'process raft request' (duration: 971.777688ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:28:26.642Z","caller":"traceutil/trace.go:171","msg":"trace[2052333645] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"972.776527ms","start":"2026-08-07T02:28:25.669187Z","end":"2026-08-07T02:28:26.641964Z","steps":["trace[2052333645] 'process raft request' (duration: 972.608984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:26.642095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.670029Z","time spent":"972.002032ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-07T02:28:26.642112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.669036Z","time spent":"972.990881ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-07T02:28:26.642167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.669182Z","time spent":"972.90088ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-07T02:28:26.64219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.257195Z","time spent":"1.384802212s","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-07T02:28:26.642376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.371912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T02:28:26.642452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.755996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-07T02:28:26.642696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.097195ms","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-08-07T02:28:26.643027Z","caller":"traceutil/trace.go:171","msg":"trace[492762785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:262; }","duration":"863.357368ms","start":"2026-08-07T02:28:25.779654Z","end":"2026-08-07T02:28:26.643011Z","steps":["trace[492762785] 'agreement among raft nodes before linearized reading' (duration: 862.689305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:26.643065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.779638Z","time spent":"863.416629ms","remote":"127.0.0.1:45046","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-08-07T02:28:26.643066Z","caller":"traceutil/trace.go:171","msg":"trace[113420741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:262; }","duration":"967.487294ms","start":"2026-08-07T02:28:25.675556Z","end":"2026-08-07T02:28:26.643044Z","steps":["trace[113420741] 'agreement among raft nodes before linearized reading' (duration: 967.035874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:26.643098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.111474ms","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-08-07T02:28:26.643123Z","caller":"traceutil/trace.go:171","msg":"trace[150884538] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:262; }","duration":"974.163134ms","start":"2026-08-07T02:28:25.668953Z","end":"2026-08-07T02:28:26.643117Z","steps":["trace[150884538] 'agreement among raft nodes before linearized reading' (duration: 974.085793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:26.643117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.67555Z","time spent":"967.558965ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T02:28:26.643149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.668939Z","time spent":"974.203035ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-08-07T02:28:26.643223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293054179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:28:26.643255Z","caller":"traceutil/trace.go:171","msg":"trace[901023410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:262; }","duration":"1.293126031s","start":"2026-08-07T02:28:25.350118Z","end":"2026-08-07T02:28:26.643244Z","steps":["trace[901023410] 'agreement among raft nodes before linearized reading' (duration: 1.29307349s)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:28:26.643018Z","caller":"traceutil/trace.go:171","msg":"trace[697987655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:262; }","duration":"987.073076ms","start":"2026-08-07T02:28:25.655931Z","end":"2026-08-07T02:28:26.643004Z","steps":["trace[697987655] 'agreement among raft nodes before linearized reading' (duration: 986.324331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:26.64328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.350094Z","time spent":"1.293181011s","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:28:26.643288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.655917Z","time spent":"987.364412ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-07T02:28:26.643057Z","caller":"traceutil/trace.go:171","msg":"trace[1373528849] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"973.94531ms","start":"2026-08-07T02:28:25.669098Z","end":"2026-08-07T02:28:26.643043Z","steps":["trace[1373528849] 'process raft request' (duration: 972.674795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:28:26.643374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:28:25.669095Z","time spent":"974.241885ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-07T02:28:26.643649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.657849ms","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-08-07T02:28:26.643688Z","caller":"traceutil/trace.go:171","msg":"trace[430029053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:262; }","duration":"276.72552ms","start":"2026-08-07T02:28:26.366951Z","end":"2026-08-07T02:28:26.643677Z","steps":["trace[430029053] 'agreement among raft nodes before linearized reading' (duration: 276.437274ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:01.677085Z","caller":"traceutil/trace.go:171","msg":"trace[463782419] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"116.445633ms","start":"2026-08-07T02:29:01.560612Z","end":"2026-08-07T02:29:01.677057Z","steps":["trace[463782419] 'process raft request' (duration: 116.246279ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:02.210663Z","caller":"traceutil/trace.go:171","msg":"trace[1706074122] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"530.046213ms","start":"2026-08-07T02:29:01.6806Z","end":"2026-08-07T02:29:02.210646Z","steps":["trace[1706074122] 'process raft request' (duration: 529.959442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:02.210752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:01.680581Z","time spent":"530.130836ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T02:29:02.344975Z","caller":"traceutil/trace.go:171","msg":"trace[1527222848] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"664.205932ms","start":"2026-08-07T02:29:01.680744Z","end":"2026-08-07T02:29:02.34495Z","steps":["trace[1527222848] 'process raft request' (duration: 663.653081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:02.345114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:01.680731Z","time spent":"664.328094ms","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T02:29:02.345696Z","caller":"traceutil/trace.go:171","msg":"trace[1379356205] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"277.702689ms","start":"2026-08-07T02:29:02.06797Z","end":"2026-08-07T02:29:02.345673Z","steps":["trace[1379356205] 'process raft request' (duration: 276.905873ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:02.941463Z","caller":"traceutil/trace.go:171","msg":"trace[1981710815] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"226.347247ms","start":"2026-08-07T02:29:02.715091Z","end":"2026-08-07T02:29:02.941438Z","steps":["trace[1981710815] 'process raft request' (duration: 226.060531ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:03.815819Z","caller":"traceutil/trace.go:171","msg":"trace[1850721609] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"988.749933ms","start":"2026-08-07T02:29:02.827049Z","end":"2026-08-07T02:29:03.815799Z","steps":["trace[1850721609] 'read index received' (duration: 114.182997ms)","trace[1850721609] 'applied index is now lower than readState.Index' (duration: 874.566346ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:03.816349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.274813ms","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-08-07T02:29:03.816441Z","caller":"traceutil/trace.go:171","msg":"trace[839435775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"989.415786ms","start":"2026-08-07T02:29:02.827006Z","end":"2026-08-07T02:29:03.816422Z","steps":["trace[839435775] 'agreement among raft nodes before linearized reading' (duration: 989.178872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:03.816489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:02.826987Z","time spent":"989.491388ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T02:29:03.816474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.397478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-07T02:29:03.816524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.330208ms","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-08-07T02:29:03.816549Z","caller":"traceutil/trace.go:171","msg":"trace[285550564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"986.49858ms","start":"2026-08-07T02:29:02.83003Z","end":"2026-08-07T02:29:03.816529Z","steps":["trace[285550564] 'agreement among raft nodes before linearized reading' (duration: 986.313306ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:03.816561Z","caller":"traceutil/trace.go:171","msg":"trace[1878305747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"984.384538ms","start":"2026-08-07T02:29:02.832165Z","end":"2026-08-07T02:29:03.81655Z","steps":["trace[1878305747] 'agreement among raft nodes before linearized reading' (duration: 984.299507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:03.816595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:02.832123Z","time spent":"984.467031ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T02:29:03.816599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:02.830015Z","time spent":"986.574902ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:29:03.816599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.827117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.118\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-07T02:29:03.816473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.145034ms","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-08-07T02:29:03.81666Z","caller":"traceutil/trace.go:171","msg":"trace[1840510381] range","detail":"{range_begin:/registry/masterleases/199.19.213.118; range_end:; response_count:1; response_revision:574; }","duration":"698.917769ms","start":"2026-08-07T02:29:03.117726Z","end":"2026-08-07T02:29:03.816644Z","steps":["trace[1840510381] 'agreement among raft nodes before linearized reading' (duration: 698.786977ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:03.816677Z","caller":"traceutil/trace.go:171","msg":"trace[1850021433] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.015399095s","start":"2026-08-07T02:29:02.801271Z","end":"2026-08-07T02:29:03.81667Z","steps":["trace[1850021433] 'process raft request' (duration: 1.006424029s)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:03.816685Z","caller":"traceutil/trace.go:171","msg":"trace[102985967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"984.381629ms","start":"2026-08-07T02:29:02.83229Z","end":"2026-08-07T02:29:03.816672Z","steps":["trace[102985967] 'agreement among raft nodes before linearized reading' (duration: 984.056622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:03.816694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:03.117709Z","time spent":"698.97763ms","remote":"127.0.0.1:44846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.118\" "} {"level":"warn","ts":"2026-08-07T02:29:03.816724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:02.83228Z","time spent":"984.43553ms","remote":"127.0.0.1:45046","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-08-07T02:29:03.816957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:02.801248Z","time spent":"1.015444365s","remote":"127.0.0.1:44896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:29:06.854878Z","caller":"traceutil/trace.go:171","msg":"trace[770594929] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"141.978561ms","start":"2026-08-07T02:29:06.71287Z","end":"2026-08-07T02:29:06.854848Z","steps":["trace[770594929] 'process raft request' (duration: 141.072883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:08.339378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129476411,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T02:29:08.514729Z","caller":"traceutil/trace.go:171","msg":"trace[1380236930] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"676.396968ms","start":"2026-08-07T02:29:07.838309Z","end":"2026-08-07T02:29:08.514706Z","steps":["trace[1380236930] 'read index received' (duration: 676.162864ms)","trace[1380236930] 'applied index is now lower than readState.Index' (duration: 233.284µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:08.514784Z","caller":"traceutil/trace.go:171","msg":"trace[813801543] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"723.294934ms","start":"2026-08-07T02:29:07.791456Z","end":"2026-08-07T02:29:08.514751Z","steps":["trace[813801543] 'process raft request' (duration: 723.030749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:08.514916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.586323ms","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-08-07T02:29:08.514955Z","caller":"traceutil/trace.go:171","msg":"trace[1075890552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"676.670694ms","start":"2026-08-07T02:29:07.838274Z","end":"2026-08-07T02:29:08.514945Z","steps":["trace[1075890552] 'agreement among raft nodes before linearized reading' (duration: 676.529401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:08.514965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:07.791391Z","time spent":"723.485399ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:08.514987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:07.838262Z","time spent":"676.717315ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-07T02:29:08.619476Z","caller":"traceutil/trace.go:171","msg":"trace[214312363] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"104.605501ms","start":"2026-08-07T02:29:08.514782Z","end":"2026-08-07T02:29:08.619387Z","steps":["trace[214312363] 'read index received' (duration: 31.595117ms)","trace[214312363] 'applied index is now lower than readState.Index' (duration: 73.005294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:08.668769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.018497ms","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-08-07T02:29:08.668844Z","caller":"traceutil/trace.go:171","msg":"trace[422868518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"829.14092ms","start":"2026-08-07T02:29:07.839686Z","end":"2026-08-07T02:29:08.668826Z","steps":["trace[422868518] 'agreement among raft nodes before linearized reading' (duration: 779.927949ms)","trace[422868518] 'range keys from in-memory index tree' (duration: 49.010736ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:08.668879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:07.839678Z","time spent":"829.194361ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:29:08.668894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.621548ms","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-08-07T02:29:08.668927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.806142ms","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-08-07T02:29:08.669018Z","caller":"traceutil/trace.go:171","msg":"trace[1755025741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"829.793752ms","start":"2026-08-07T02:29:07.839194Z","end":"2026-08-07T02:29:08.668988Z","steps":["trace[1755025741] 'agreement among raft nodes before linearized reading' (duration: 780.431179ms)","trace[1755025741] 'range keys from in-memory index tree' (duration: 48.977766ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:08.669056Z","caller":"traceutil/trace.go:171","msg":"trace[1808466381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"829.948754ms","start":"2026-08-07T02:29:07.839063Z","end":"2026-08-07T02:29:08.669012Z","steps":["trace[1808466381] 'agreement among raft nodes before linearized reading' (duration: 780.51756ms)","trace[1808466381] 'range keys from in-memory index tree' (duration: 49.109379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:08.669087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:07.839184Z","time spent":"829.892443ms","remote":"127.0.0.1:45046","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-08-07T02:29:08.669149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:07.838971Z","time spent":"830.145299ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T02:29:09.898559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048893988s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:29:09.898852Z","caller":"traceutil/trace.go:171","msg":"trace[1546816190] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"779.301427ms","start":"2026-08-07T02:29:09.119528Z","end":"2026-08-07T02:29:09.898829Z","steps":["trace[1546816190] 'read index received' (duration: 33.44µs)","trace[1546816190] 'applied index is now lower than readState.Index' (duration: 779.266487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:09.899063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.513282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:09.899163Z","caller":"traceutil/trace.go:171","msg":"trace[304717654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"779.645474ms","start":"2026-08-07T02:29:09.119487Z","end":"2026-08-07T02:29:09.899133Z","steps":["trace[304717654] 'agreement among raft nodes before linearized reading' (duration: 779.483871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:09.899179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.959383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:09.899219Z","caller":"traceutil/trace.go:171","msg":"trace[689908308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"548.034765ms","start":"2026-08-07T02:29:09.351171Z","end":"2026-08-07T02:29:09.899206Z","steps":["trace[689908308] 'agreement among raft nodes before linearized reading' (duration: 547.969734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:09.899256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:09.351147Z","time spent":"548.101186ms","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:29:09.899287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:09.119471Z","time spent":"779.802457ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T02:29:09.899369Z","caller":"traceutil/trace.go:171","msg":"trace[590716584] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.225582236s","start":"2026-08-07T02:29:08.67374Z","end":"2026-08-07T02:29:09.899323Z","steps":["trace[590716584] 'process raft request' (duration: 175.557815ms)","trace[590716584] 'compare' (duration: 1.048626043s)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:09.89954Z","caller":"traceutil/trace.go:171","msg":"trace[1171500575] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.22530099s","start":"2026-08-07T02:29:08.674225Z","end":"2026-08-07T02:29:09.899526Z","steps":["trace[1171500575] 'process raft request' (duration: 1.224474905s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:09.899653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:08.674213Z","time spent":"1.225359061s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:09.899663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:08.673706Z","time spent":"1.2258158s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:10.619158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.51273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:10.619249Z","caller":"traceutil/trace.go:171","msg":"trace[225738679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"268.613712ms","start":"2026-08-07T02:29:10.350614Z","end":"2026-08-07T02:29:10.619227Z","steps":["trace[225738679] 'range keys from in-memory index tree' (duration: 268.395578ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:12.306016Z","caller":"traceutil/trace.go:171","msg":"trace[527466719] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"397.120299ms","start":"2026-08-07T02:29:11.908861Z","end":"2026-08-07T02:29:12.305982Z","steps":["trace[527466719] 'process raft request' (duration: 396.927286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:12.30621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:11.908833Z","time spent":"397.265492ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:29:12.316971Z","caller":"traceutil/trace.go:171","msg":"trace[1114871102] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"328.053143ms","start":"2026-08-07T02:29:11.9889Z","end":"2026-08-07T02:29:12.316954Z","steps":["trace[1114871102] 'process raft request' (duration: 328.002172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:12.31708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:11.988857Z","time spent":"328.171715ms","remote":"127.0.0.1:44860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-07T02:29:12.317042Z","caller":"traceutil/trace.go:171","msg":"trace[1080588859] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"407.772997ms","start":"2026-08-07T02:29:11.909242Z","end":"2026-08-07T02:29:12.317015Z","steps":["trace[1080588859] 'process raft request' (duration: 407.560483ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:12.317216Z","caller":"traceutil/trace.go:171","msg":"trace[133208063] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"128.185282ms","start":"2026-08-07T02:29:12.189016Z","end":"2026-08-07T02:29:12.317201Z","steps":["trace[133208063] 'process raft request' (duration: 127.915597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:12.317472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:11.909225Z","time spent":"408.025173ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:29:15.131593Z","caller":"traceutil/trace.go:171","msg":"trace[619532421] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"139.509263ms","start":"2026-08-07T02:29:14.99206Z","end":"2026-08-07T02:29:15.131569Z","steps":["trace[619532421] 'process raft request' (duration: 139.36922ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:25.816167Z","caller":"traceutil/trace.go:171","msg":"trace[1814565843] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:790; }","duration":"464.82502ms","start":"2026-08-07T02:29:25.351296Z","end":"2026-08-07T02:29:25.816121Z","steps":["trace[1814565843] 'read index received' (duration: 464.80206ms)","trace[1814565843] 'applied index is now lower than readState.Index' (duration: 18.86µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:25.816164Z","caller":"traceutil/trace.go:171","msg":"trace[1371176030] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"913.335173ms","start":"2026-08-07T02:29:24.902794Z","end":"2026-08-07T02:29:25.81613Z","steps":["trace[1371176030] 'process raft request' (duration: 913.147609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:25.816426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:24.902777Z","time spent":"913.547356ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:25.816619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.280019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:25.816938Z","caller":"traceutil/trace.go:171","msg":"trace[280420873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"465.667707ms","start":"2026-08-07T02:29:25.35124Z","end":"2026-08-07T02:29:25.816908Z","steps":["trace[280420873] 'agreement among raft nodes before linearized reading' (duration: 465.203167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:25.817028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:25.351216Z","time spent":"465.79829ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T02:29:25.821315Z","caller":"traceutil/trace.go:171","msg":"trace[158273364] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"382.742239ms","start":"2026-08-07T02:29:25.43854Z","end":"2026-08-07T02:29:25.821282Z","steps":["trace[158273364] 'process raft request' (duration: 382.516375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:25.821502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:25.43852Z","time spent":"382.879801ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:29:40.214959Z","caller":"traceutil/trace.go:171","msg":"trace[597434079] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"281.671226ms","start":"2026-08-07T02:29:39.933258Z","end":"2026-08-07T02:29:40.214929Z","steps":["trace[597434079] 'read index received' (duration: 281.578534ms)","trace[597434079] 'applied index is now lower than readState.Index' (duration: 91.332µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:40.215009Z","caller":"traceutil/trace.go:171","msg":"trace[846344511] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"855.201149ms","start":"2026-08-07T02:29:39.359768Z","end":"2026-08-07T02:29:40.21497Z","steps":["trace[846344511] 'process raft request' (duration: 854.989195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:40.215178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.896051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-07T02:29:40.215222Z","caller":"traceutil/trace.go:171","msg":"trace[1150514684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"282.013002ms","start":"2026-08-07T02:29:39.933196Z","end":"2026-08-07T02:29:40.215209Z","steps":["trace[1150514684] 'agreement among raft nodes before linearized reading' (duration: 281.838189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:40.21523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:39.35974Z","time spent":"855.366141ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:40.456851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.410645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:29:40.457008Z","caller":"traceutil/trace.go:171","msg":"trace[1944928390] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"394.808245ms","start":"2026-08-07T02:29:40.062178Z","end":"2026-08-07T02:29:40.456987Z","steps":["trace[1944928390] 'process raft request' (duration: 201.167105ms)","trace[1944928390] 'compare' (duration: 193.124999ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:40.457633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.062157Z","time spent":"395.419096ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:40.823055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T02:29:41.323776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T02:29:41.407319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.02182928s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T02:29:41.465742Z","caller":"traceutil/trace.go:171","msg":"trace[1156560623] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.244709839s","start":"2026-08-07T02:29:40.221006Z","end":"2026-08-07T02:29:41.465716Z","steps":["trace[1156560623] 'process raft request' (duration: 1.186583195s)","trace[1156560623] 'compare' (duration: 57.571374ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:41.465886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.220984Z","time spent":"1.244813431s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:29:41.466033Z","caller":"traceutil/trace.go:171","msg":"trace[391385717] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"1.244620637s","start":"2026-08-07T02:29:40.221397Z","end":"2026-08-07T02:29:41.466017Z","steps":["trace[391385717] 'process raft request' (duration: 1.24427486s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.221371Z","time spent":"1.244707059s","remote":"127.0.0.1:45060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:29:41.466235Z","caller":"traceutil/trace.go:171","msg":"trace[2114468076] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:931; }","duration":"1.144037794s","start":"2026-08-07T02:29:40.322172Z","end":"2026-08-07T02:29:41.46621Z","steps":["trace[2114468076] 'read index received' (duration: 1.085320818s)","trace[2114468076] 'applied index is now lower than readState.Index' (duration: 58.715556ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:41.466521Z","caller":"traceutil/trace.go:171","msg":"trace[1956756556] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.243322502s","start":"2026-08-07T02:29:40.223188Z","end":"2026-08-07T02:29:41.466511Z","steps":["trace[1956756556] 'process raft request' (duration: 1.242647238s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116460357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:41.466615Z","caller":"traceutil/trace.go:171","msg":"trace[820261853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"1.116514288s","start":"2026-08-07T02:29:40.350088Z","end":"2026-08-07T02:29:41.466603Z","steps":["trace[820261853] 'agreement among raft nodes before linearized reading' (duration: 1.116454917s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.350076Z","time spent":"1.116560498s","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-07T02:29:41.466632Z","caller":"traceutil/trace.go:171","msg":"trace[1822798888] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"1.245265249s","start":"2026-08-07T02:29:40.221349Z","end":"2026-08-07T02:29:41.466614Z","steps":["trace[1822798888] 'process raft request' (duration: 1.244268609s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.223161Z","time spent":"1.243384523s","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:41.466726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.221327Z","time spent":"1.245351472s","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:29:41.46677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.42002ms","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-08-07T02:29:41.466807Z","caller":"traceutil/trace.go:171","msg":"trace[736651216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"552.512012ms","start":"2026-08-07T02:29:40.914284Z","end":"2026-08-07T02:29:41.466796Z","steps":["trace[736651216] 'agreement among raft nodes before linearized reading' (duration: 552.36775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.914267Z","time spent":"552.561793ms","remote":"127.0.0.1:45046","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-08-07T02:29:41.466848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.867396ms","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-08-07T02:29:41.466877Z","caller":"traceutil/trace.go:171","msg":"trace[1337217638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"640.922007ms","start":"2026-08-07T02:29:40.825947Z","end":"2026-08-07T02:29:41.466869Z","steps":["trace[1337217638] 'agreement among raft nodes before linearized reading' (duration: 640.835145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.82593Z","time spent":"640.966658ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T02:29:41.466916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144744357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:41.46694Z","caller":"traceutil/trace.go:171","msg":"trace[614585592] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:902; }","duration":"1.14481275s","start":"2026-08-07T02:29:40.322119Z","end":"2026-08-07T02:29:41.466932Z","steps":["trace[614585592] 'agreement among raft nodes before linearized reading' (duration: 1.144775049s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.466964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.322097Z","time spent":"1.1448611s","remote":"127.0.0.1:45214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:29:41.467044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.862706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T02:29:41.46705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.585219ms","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-08-07T02:29:41.467073Z","caller":"traceutil/trace.go:171","msg":"trace[1391276801] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:902; }","duration":"310.925207ms","start":"2026-08-07T02:29:41.156139Z","end":"2026-08-07T02:29:41.467064Z","steps":["trace[1391276801] 'agreement among raft nodes before linearized reading' (duration: 310.876616ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:29:41.467088Z","caller":"traceutil/trace.go:171","msg":"trace[1349543725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"971.652951ms","start":"2026-08-07T02:29:40.495424Z","end":"2026-08-07T02:29:41.467077Z","steps":["trace[1349543725] 'agreement among raft nodes before linearized reading' (duration: 971.516788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.467103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:41.156122Z","time spent":"310.973658ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:29:41.46712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.495392Z","time spent":"971.721441ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:29:41.467159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.021146ms","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-08-07T02:29:41.467194Z","caller":"traceutil/trace.go:171","msg":"trace[1122580523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:902; }","duration":"955.071117ms","start":"2026-08-07T02:29:40.512113Z","end":"2026-08-07T02:29:41.467184Z","steps":["trace[1122580523] 'agreement among raft nodes before linearized reading' (duration: 954.968045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:41.467222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:29:40.512105Z","time spent":"955.110887ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-07T02:29:58.15281Z","caller":"traceutil/trace.go:171","msg":"trace[1293377164] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"136.541259ms","start":"2026-08-07T02:29:58.016235Z","end":"2026-08-07T02:29:58.152777Z","steps":["trace[1293377164] 'process raft request' (duration: 136.369185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:29:58.504013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.340376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:4149"} {"level":"info","ts":"2026-08-07T02:29:58.504334Z","caller":"traceutil/trace.go:171","msg":"trace[813765249] transaction","detail":"{read_only:false; number_of_response:1; response_revision:968; }","duration":"236.592876ms","start":"2026-08-07T02:29:58.267684Z","end":"2026-08-07T02:29:58.504276Z","steps":["trace[813765249] 'process raft request' (duration: 103.485036ms)","trace[813765249] 'compare' (duration: 132.254014ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:29:58.504333Z","caller":"traceutil/trace.go:171","msg":"trace[765548703] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"144.56615ms","start":"2026-08-07T02:29:58.359708Z","end":"2026-08-07T02:29:58.504274Z","steps":["trace[765548703] 'read index received' (duration: 11.347688ms)","trace[765548703] 'applied index is now lower than readState.Index' (duration: 133.212512ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:29:58.575646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.913421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:29:58.575735Z","caller":"traceutil/trace.go:171","msg":"trace[1378582833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"216.065325ms","start":"2026-08-07T02:29:58.359649Z","end":"2026-08-07T02:29:58.575714Z","steps":["trace[1378582833] 'agreement among raft nodes before linearized reading' (duration: 144.774155ms)","trace[1378582833] 'range keys from in-memory index tree' (duration: 71.142436ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:11.929753Z","caller":"traceutil/trace.go:171","msg":"trace[287473415] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"239.913443ms","start":"2026-08-07T02:30:11.689805Z","end":"2026-08-07T02:30:11.929719Z","steps":["trace[287473415] 'read index received' (duration: 239.683929ms)","trace[287473415] 'applied index is now lower than readState.Index' (duration: 227.994µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:11.929811Z","caller":"traceutil/trace.go:171","msg":"trace[957732015] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"307.843126ms","start":"2026-08-07T02:30:11.621927Z","end":"2026-08-07T02:30:11.92977Z","steps":["trace[957732015] 'process raft request' (duration: 307.601852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:11.93001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:11.621914Z","time spent":"308.001029ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:11.930006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.736883ms","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-08-07T02:30:11.930081Z","caller":"traceutil/trace.go:171","msg":"trace[465559672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"307.845415ms","start":"2026-08-07T02:30:11.62222Z","end":"2026-08-07T02:30:11.930066Z","steps":["trace[465559672] 'agreement among raft nodes before linearized reading' (duration: 307.616001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:11.930112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:11.622215Z","time spent":"307.891996ms","remote":"127.0.0.1:45046","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-08-07T02:30:11.930001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.221123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-07T02:30:11.93019Z","caller":"traceutil/trace.go:171","msg":"trace[1106126573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"307.429407ms","start":"2026-08-07T02:30:11.622731Z","end":"2026-08-07T02:30:11.930161Z","steps":["trace[1106126573] 'agreement among raft nodes before linearized reading' (duration: 307.07662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:11.930427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:11.622723Z","time spent":"307.687583ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:11.971933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.767577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:11.972029Z","caller":"traceutil/trace.go:171","msg":"trace[1702206251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"204.88261ms","start":"2026-08-07T02:30:11.767128Z","end":"2026-08-07T02:30:11.97201Z","steps":["trace[1702206251] 'agreement among raft nodes before linearized reading' (duration: 204.703326ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:11.972083Z","caller":"traceutil/trace.go:171","msg":"trace[1920285712] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"278.095238ms","start":"2026-08-07T02:30:11.693947Z","end":"2026-08-07T02:30:11.972042Z","steps":["trace[1920285712] 'process raft request' (duration: 277.728001ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:13.924214Z","caller":"traceutil/trace.go:171","msg":"trace[920847945] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"226.675887ms","start":"2026-08-07T02:30:13.697505Z","end":"2026-08-07T02:30:13.924181Z","steps":["trace[920847945] 'process raft request' (duration: 226.515064ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:15.342444Z","caller":"traceutil/trace.go:171","msg":"trace[120337106] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"743.858145ms","start":"2026-08-07T02:30:14.598546Z","end":"2026-08-07T02:30:15.342404Z","steps":["trace[120337106] 'process raft request' (duration: 743.637019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:15.342675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:14.598518Z","time spent":"744.030658ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:16.342618Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000430282s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T02:30:16.343356Z","caller":"traceutil/trace.go:171","msg":"trace[1055343982] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.622719135s","start":"2026-08-07T02:30:14.720599Z","end":"2026-08-07T02:30:16.343318Z","steps":["trace[1055343982] 'process raft request' (duration: 1.622189675s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.343495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166299329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:16.343545Z","caller":"traceutil/trace.go:171","msg":"trace[1671847038] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1024; }","duration":"1.16641688s","start":"2026-08-07T02:30:15.177112Z","end":"2026-08-07T02:30:16.343529Z","steps":["trace[1671847038] 'agreement among raft nodes before linearized reading' (duration: 1.166313958s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.343588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:15.177087Z","time spent":"1.166489013s","remote":"127.0.0.1:45242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:16.343604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:14.720563Z","time spent":"1.622932911s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:16.343374Z","caller":"traceutil/trace.go:171","msg":"trace[967855485] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1064; }","duration":"1.165869259s","start":"2026-08-07T02:30:15.177173Z","end":"2026-08-07T02:30:16.343042Z","steps":["trace[967855485] 'read index received' (duration: 165.04686ms)","trace[967855485] 'applied index is now lower than readState.Index' (duration: 1.000821309s)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:16.344409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.920658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-07T02:30:16.344471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.943032ms","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-08-07T02:30:16.344483Z","caller":"traceutil/trace.go:171","msg":"trace[407859920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"324.044081ms","start":"2026-08-07T02:30:16.020422Z","end":"2026-08-07T02:30:16.344467Z","steps":["trace[407859920] 'agreement among raft nodes before linearized reading' (duration: 323.800206ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:16.344509Z","caller":"traceutil/trace.go:171","msg":"trace[1154272049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"371.038614ms","start":"2026-08-07T02:30:15.973459Z","end":"2026-08-07T02:30:16.344498Z","steps":["trace[1154272049] 'agreement among raft nodes before linearized reading' (duration: 370.874751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.344528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.020395Z","time spent":"324.124452ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:16.344541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:15.973428Z","time spent":"371.106275ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:16.344709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.923394ms","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-08-07T02:30:16.344764Z","caller":"traceutil/trace.go:171","msg":"trace[697116674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"416.070417ms","start":"2026-08-07T02:30:15.928685Z","end":"2026-08-07T02:30:16.344755Z","steps":["trace[697116674] 'agreement among raft nodes before linearized reading' (duration: 415.480936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.344797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:15.928663Z","time spent":"416.127018ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T02:30:16.344863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.105332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-07T02:30:16.344997Z","caller":"traceutil/trace.go:171","msg":"trace[60200045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"322.398358ms","start":"2026-08-07T02:30:16.022567Z","end":"2026-08-07T02:30:16.344966Z","steps":["trace[60200045] 'agreement among raft nodes before linearized reading' (duration: 321.749785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.345109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.022557Z","time spent":"322.533442ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:16.34512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.834119ms","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-08-07T02:30:16.345499Z","caller":"traceutil/trace.go:171","msg":"trace[525836210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"353.208616ms","start":"2026-08-07T02:30:15.992272Z","end":"2026-08-07T02:30:16.345481Z","steps":["trace[525836210] 'agreement among raft nodes before linearized reading' (duration: 351.998622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.345603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:15.992207Z","time spent":"353.378209ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:30:16.961825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.405126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:30:16.962136Z","caller":"traceutil/trace.go:171","msg":"trace[187311462] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"612.335334ms","start":"2026-08-07T02:30:16.349777Z","end":"2026-08-07T02:30:16.962112Z","steps":["trace[187311462] 'read index received' (duration: 49.942442ms)","trace[187311462] 'applied index is now lower than readState.Index' (duration: 562.391472ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:16.962254Z","caller":"traceutil/trace.go:171","msg":"trace[1956545208] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"612.5774ms","start":"2026-08-07T02:30:16.349661Z","end":"2026-08-07T02:30:16.962238Z","steps":["trace[1956545208] 'process raft request' (duration: 150.652363ms)","trace[1956545208] 'compare' (duration: 461.260674ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:16.962328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.349648Z","time spent":"612.642422ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:16.962319Z","caller":"traceutil/trace.go:171","msg":"trace[1557025482] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"611.55228ms","start":"2026-08-07T02:30:16.350742Z","end":"2026-08-07T02:30:16.962294Z","steps":["trace[1557025482] 'process raft request' (duration: 611.326224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.962458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.350732Z","time spent":"611.653271ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:16.962424Z","caller":"traceutil/trace.go:171","msg":"trace[1031683902] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"612.451038ms","start":"2026-08-07T02:30:16.349847Z","end":"2026-08-07T02:30:16.962298Z","steps":["trace[1031683902] 'process raft request' (duration: 612.08264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.962631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.822765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:16.962767Z","caller":"traceutil/trace.go:171","msg":"trace[1710147865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"612.991098ms","start":"2026-08-07T02:30:16.349737Z","end":"2026-08-07T02:30:16.962728Z","steps":["trace[1710147865] 'agreement among raft nodes before linearized reading' (duration: 612.736123ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:16.962791Z","caller":"traceutil/trace.go:171","msg":"trace[1792389192] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"612.778314ms","start":"2026-08-07T02:30:16.349999Z","end":"2026-08-07T02:30:16.962778Z","steps":["trace[1792389192] 'process raft request' (duration: 612.014018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.962813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.34983Z","time spent":"612.806044ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:16.962873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.349983Z","time spent":"612.852725ms","remote":"127.0.0.1:45046","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-08-07T02:30:16.962838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.349717Z","time spent":"613.095951ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:30:16.963047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.842885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:16.963091Z","caller":"traceutil/trace.go:171","msg":"trace[2030054848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1029; }","duration":"454.885407ms","start":"2026-08-07T02:30:16.508192Z","end":"2026-08-07T02:30:16.963077Z","steps":["trace[2030054848] 'agreement among raft nodes before linearized reading' (duration: 454.816865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.963132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.508165Z","time spent":"454.955798ms","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:16.963349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.228052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:16.963497Z","caller":"traceutil/trace.go:171","msg":"trace[918115024] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1029; }","duration":"598.432236ms","start":"2026-08-07T02:30:16.365052Z","end":"2026-08-07T02:30:16.963484Z","steps":["trace[918115024] 'agreement among raft nodes before linearized reading' (duration: 597.414256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:16.963572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:16.365028Z","time spent":"598.527227ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:17.845606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477334,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T02:30:18.005433Z","caller":"traceutil/trace.go:171","msg":"trace[1641648708] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"660.643534ms","start":"2026-08-07T02:30:17.344762Z","end":"2026-08-07T02:30:18.005405Z","steps":["trace[1641648708] 'read index received' (duration: 660.46869ms)","trace[1641648708] 'applied index is now lower than readState.Index' (duration: 173.204µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:18.005499Z","caller":"traceutil/trace.go:171","msg":"trace[651038173] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"742.634269ms","start":"2026-08-07T02:30:17.262848Z","end":"2026-08-07T02:30:18.005482Z","steps":["trace[651038173] 'process raft request' (duration: 742.366263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:18.005618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.847838ms","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-08-07T02:30:18.005636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:17.262824Z","time spent":"742.718222ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:18.005656Z","caller":"traceutil/trace.go:171","msg":"trace[709456971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1030; }","duration":"660.90225ms","start":"2026-08-07T02:30:17.344743Z","end":"2026-08-07T02:30:18.005645Z","steps":["trace[709456971] 'agreement among raft nodes before linearized reading' (duration: 660.820598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:18.005691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:17.344677Z","time spent":"661.004151ms","remote":"127.0.0.1:57236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:18.005708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.718355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T02:30:18.005749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.557926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-07T02:30:18.005778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.713789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T02:30:18.005777Z","caller":"traceutil/trace.go:171","msg":"trace[1771094993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"653.854318ms","start":"2026-08-07T02:30:17.351906Z","end":"2026-08-07T02:30:18.00576Z","steps":["trace[1771094993] 'agreement among raft nodes before linearized reading' (duration: 653.705865ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:18.00579Z","caller":"traceutil/trace.go:171","msg":"trace[897259422] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1030; }","duration":"234.638258ms","start":"2026-08-07T02:30:17.77114Z","end":"2026-08-07T02:30:18.005779Z","steps":["trace[897259422] 'agreement among raft nodes before linearized reading' (duration: 234.550426ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:18.005801Z","caller":"traceutil/trace.go:171","msg":"trace[505752965] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1030; }","duration":"550.736589ms","start":"2026-08-07T02:30:17.455057Z","end":"2026-08-07T02:30:18.005794Z","steps":["trace[505752965] 'agreement among raft nodes before linearized reading' (duration: 550.697109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:18.005824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:17.454992Z","time spent":"550.826522ms","remote":"127.0.0.1:44966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:18.005829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:17.351879Z","time spent":"653.93814ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:30:19.258151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.260298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:30:19.258247Z","caller":"traceutil/trace.go:171","msg":"trace[1185018762] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"853.442672ms","start":"2026-08-07T02:30:18.404788Z","end":"2026-08-07T02:30:19.258231Z","steps":["trace[1185018762] 'read index received' (duration: 498.246636ms)","trace[1185018762] 'applied index is now lower than readState.Index' (duration: 355.193936ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:19.258292Z","caller":"traceutil/trace.go:171","msg":"trace[2026897736] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"909.66577ms","start":"2026-08-07T02:30:18.3486Z","end":"2026-08-07T02:30:19.258266Z","steps":["trace[2026897736] 'process raft request' (duration: 554.198018ms)","trace[2026897736] 'compare' (duration: 354.790809ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:19.258433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.348578Z","time spent":"909.782492ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:19.258602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.82118ms","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-08-07T02:30:19.258642Z","caller":"traceutil/trace.go:171","msg":"trace[1369091713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"853.899002ms","start":"2026-08-07T02:30:18.404733Z","end":"2026-08-07T02:30:19.258632Z","steps":["trace[1369091713] 'agreement among raft nodes before linearized reading' (duration: 853.566554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.257953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.35539Z","time spent":"902.517487ms","remote":"127.0.0.1:49518","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-07T02:30:19.258673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.404711Z","time spent":"853.955202ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:19.258057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.987501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:19.258771Z","caller":"traceutil/trace.go:171","msg":"trace[720167274] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1030; }","duration":"967.821328ms","start":"2026-08-07T02:30:18.290938Z","end":"2026-08-07T02:30:19.258759Z","steps":["trace[720167274] 'count revisions from in-memory index tree' (duration: 966.868028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.258809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.290914Z","time spent":"967.882639ms","remote":"127.0.0.1:44936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:19.486129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.288076ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-07T02:30:19.486472Z","caller":"traceutil/trace.go:171","msg":"trace[683780256] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"223.562204ms","start":"2026-08-07T02:30:19.262884Z","end":"2026-08-07T02:30:19.486446Z","steps":["trace[683780256] 'process raft request' (duration: 223.421942ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:19.486474Z","caller":"traceutil/trace.go:171","msg":"trace[673693235] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"228.157037ms","start":"2026-08-07T02:30:19.258273Z","end":"2026-08-07T02:30:19.486431Z","steps":["trace[673693235] 'read index received' (duration: 64.556955ms)","trace[673693235] 'applied index is now lower than readState.Index' (duration: 163.592642ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:19.486686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.456915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:19.486786Z","caller":"traceutil/trace.go:171","msg":"trace[926486015] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1032; }","duration":"432.62175ms","start":"2026-08-07T02:30:19.054143Z","end":"2026-08-07T02:30:19.486764Z","steps":["trace[926486015] 'agreement among raft nodes before linearized reading' (duration: 432.396414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.486834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.350279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-07T02:30:19.486849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:19.054124Z","time spent":"432.711211ms","remote":"127.0.0.1:45110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-08-07T02:30:19.48688Z","caller":"traceutil/trace.go:171","msg":"trace[1309564628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"519.497833ms","start":"2026-08-07T02:30:18.967371Z","end":"2026-08-07T02:30:19.486869Z","steps":["trace[1309564628] 'agreement among raft nodes before linearized reading' (duration: 519.271477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.486935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.967344Z","time spent":"519.582274ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:30:19.48705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.384271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:19.487098Z","caller":"traceutil/trace.go:171","msg":"trace[841116074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"367.487483ms","start":"2026-08-07T02:30:19.119598Z","end":"2026-08-07T02:30:19.487086Z","steps":["trace[841116074] 'agreement among raft nodes before linearized reading' (duration: 367.409371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.487132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:19.119568Z","time spent":"367.555895ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:30:19.487236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.498663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-07T02:30:19.487295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.585112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T02:30:19.487328Z","caller":"traceutil/trace.go:171","msg":"trace[309872586] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1032; }","duration":"787.621942ms","start":"2026-08-07T02:30:18.699697Z","end":"2026-08-07T02:30:19.487319Z","steps":["trace[309872586] 'agreement among raft nodes before linearized reading' (duration: 787.545981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.487366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.699624Z","time spent":"787.734874ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:19.487368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.798578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:19.487363Z","caller":"traceutil/trace.go:171","msg":"trace[208332969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"518.685846ms","start":"2026-08-07T02:30:18.968633Z","end":"2026-08-07T02:30:19.487319Z","steps":["trace[208332969] 'agreement among raft nodes before linearized reading' (duration: 517.963491ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:19.487441Z","caller":"traceutil/trace.go:171","msg":"trace[635386846] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1032; }","duration":"510.885169ms","start":"2026-08-07T02:30:18.976545Z","end":"2026-08-07T02:30:19.48743Z","steps":["trace[635386846] 'agreement among raft nodes before linearized reading' (duration: 510.786778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.487473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.976536Z","time spent":"510.92895ms","remote":"127.0.0.1:44892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:19.487505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.968614Z","time spent":"518.84831ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:19.487584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.809965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-07T02:30:19.487596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.570863ms","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-08-07T02:30:19.487645Z","caller":"traceutil/trace.go:171","msg":"trace[1367088706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"136.910057ms","start":"2026-08-07T02:30:19.350719Z","end":"2026-08-07T02:30:19.487629Z","steps":["trace[1367088706] 'agreement among raft nodes before linearized reading' (duration: 136.824796ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:19.487689Z","caller":"traceutil/trace.go:171","msg":"trace[1806763177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1032; }","duration":"518.676845ms","start":"2026-08-07T02:30:18.968996Z","end":"2026-08-07T02:30:19.487673Z","steps":["trace[1806763177] 'agreement among raft nodes before linearized reading' (duration: 517.736187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.48774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.968978Z","time spent":"518.748447ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T02:30:19.488027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.38678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-07T02:30:19.488087Z","caller":"traceutil/trace.go:171","msg":"trace[971349013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"518.461661ms","start":"2026-08-07T02:30:18.969611Z","end":"2026-08-07T02:30:19.488073Z","steps":["trace[971349013] 'agreement among raft nodes before linearized reading' (duration: 516.873419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:19.488162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:18.969604Z","time spent":"518.546113ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:19.806517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.070032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:30:19.806623Z","caller":"traceutil/trace.go:171","msg":"trace[654965879] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"313.084051ms","start":"2026-08-07T02:30:19.493522Z","end":"2026-08-07T02:30:19.806606Z","steps":["trace[654965879] 'process raft request' (duration: 148.848066ms)","trace[654965879] 'compare' (duration: 163.617133ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:19.806685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:19.493501Z","time spent":"313.161693ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:20.053264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.486346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:30:20.053416Z","caller":"traceutil/trace.go:171","msg":"trace[9728930] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"558.146847ms","start":"2026-08-07T02:30:19.495256Z","end":"2026-08-07T02:30:20.053403Z","steps":["trace[9728930] 'process raft request' (duration: 558.121577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:20.053464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:19.495242Z","time spent":"558.201508ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:20.05355Z","caller":"traceutil/trace.go:171","msg":"trace[1938975026] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"559.885883ms","start":"2026-08-07T02:30:19.493647Z","end":"2026-08-07T02:30:20.053533Z","steps":["trace[1938975026] 'process raft request' (duration: 559.707489ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:20.053529Z","caller":"traceutil/trace.go:171","msg":"trace[1589156016] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"559.967684ms","start":"2026-08-07T02:30:19.493522Z","end":"2026-08-07T02:30:20.053489Z","steps":["trace[1589156016] 'process raft request' (duration: 459.185022ms)","trace[1589156016] 'compare' (duration: 100.384924ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:20.053635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:19.493639Z","time spent":"559.954754ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:20.053754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:19.493504Z","time spent":"560.116747ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:21.929999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.602339ms","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-08-07T02:30:21.930109Z","caller":"traceutil/trace.go:171","msg":"trace[1208183875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"119.781983ms","start":"2026-08-07T02:30:21.8103Z","end":"2026-08-07T02:30:21.930082Z","steps":["trace[1208183875] 'range keys from in-memory index tree' (duration: 119.370765ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:22.05219Z","caller":"traceutil/trace.go:171","msg":"trace[205815593] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"117.096349ms","start":"2026-08-07T02:30:21.935066Z","end":"2026-08-07T02:30:22.052162Z","steps":["trace[205815593] 'process raft request' (duration: 116.898476ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:22.248713Z","caller":"traceutil/trace.go:171","msg":"trace[1657304323] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"185.455291ms","start":"2026-08-07T02:30:22.063229Z","end":"2026-08-07T02:30:22.248684Z","steps":["trace[1657304323] 'process raft request' (duration: 185.264607ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:22.24895Z","caller":"traceutil/trace.go:171","msg":"trace[1019765777] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"185.577323ms","start":"2026-08-07T02:30:22.063331Z","end":"2026-08-07T02:30:22.248908Z","steps":["trace[1019765777] 'process raft request' (duration: 185.310628ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:36.302063Z","caller":"traceutil/trace.go:171","msg":"trace[770160455] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"161.584202ms","start":"2026-08-07T02:30:36.140446Z","end":"2026-08-07T02:30:36.30203Z","steps":["trace[770160455] 'read index received' (duration: 161.228315ms)","trace[770160455] 'applied index is now lower than readState.Index' (duration: 351.907µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:36.302291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.816786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-07T02:30:36.302356Z","caller":"traceutil/trace.go:171","msg":"trace[1250656271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1137; }","duration":"161.944929ms","start":"2026-08-07T02:30:36.140399Z","end":"2026-08-07T02:30:36.302344Z","steps":["trace[1250656271] 'agreement among raft nodes before linearized reading' (duration: 161.731794ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:36.302192Z","caller":"traceutil/trace.go:171","msg":"trace[1414730074] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"181.072953ms","start":"2026-08-07T02:30:36.121063Z","end":"2026-08-07T02:30:36.302136Z","steps":["trace[1414730074] 'process raft request' (duration: 180.617234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:36.612211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.50134ms","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-08-07T02:30:36.612269Z","caller":"traceutil/trace.go:171","msg":"trace[1764520528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1138; }","duration":"297.582601ms","start":"2026-08-07T02:30:36.314673Z","end":"2026-08-07T02:30:36.612255Z","steps":["trace[1764520528] 'agreement among raft nodes before linearized reading' (duration: 53.398461ms)","trace[1764520528] 'range keys from in-memory index tree' (duration: 244.018056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:36.612414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.242233ms","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-08-07T02:30:36.61248Z","caller":"traceutil/trace.go:171","msg":"trace[1540819614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1138; }","duration":"301.367176ms","start":"2026-08-07T02:30:36.311095Z","end":"2026-08-07T02:30:36.612462Z","steps":["trace[1540819614] 'agreement among raft nodes before linearized reading' (duration: 57.007683ms)","trace[1540819614] 'range keys from in-memory index tree' (duration: 243.992566ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:36.612512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:36.311077Z","time spent":"301.429827ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T02:30:37.849957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477588,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T02:30:38.113561Z","caller":"traceutil/trace.go:171","msg":"trace[2058237385] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"772.487498ms","start":"2026-08-07T02:30:37.341048Z","end":"2026-08-07T02:30:38.113535Z","steps":["trace[2058237385] 'process raft request' (duration: 772.359216ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:38.113623Z","caller":"traceutil/trace.go:171","msg":"trace[599630175] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"763.685071ms","start":"2026-08-07T02:30:37.349907Z","end":"2026-08-07T02:30:38.113592Z","steps":["trace[599630175] 'read index received' (duration: 763.577469ms)","trace[599630175] 'applied index is now lower than readState.Index' (duration: 104.802µs)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:38.113684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:37.34102Z","time spent":"772.598911ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:30:38.113885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.953746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:38.113948Z","caller":"traceutil/trace.go:171","msg":"trace[305202223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"764.050688ms","start":"2026-08-07T02:30:37.349873Z","end":"2026-08-07T02:30:38.113924Z","steps":["trace[305202223] 'agreement among raft nodes before linearized reading' (duration: 763.879384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:38.114012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:37.349865Z","time spent":"764.137319ms","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:30:38.114065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.499704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:38.114092Z","caller":"traceutil/trace.go:171","msg":"trace[1323573993] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1141; }","duration":"399.557396ms","start":"2026-08-07T02:30:37.714527Z","end":"2026-08-07T02:30:38.114084Z","steps":["trace[1323573993] 'agreement among raft nodes before linearized reading' (duration: 399.517395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:38.114093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.337559ms","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-08-07T02:30:38.114144Z","caller":"traceutil/trace.go:171","msg":"trace[2117143216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1141; }","duration":"514.391941ms","start":"2026-08-07T02:30:37.59974Z","end":"2026-08-07T02:30:38.114132Z","steps":["trace[2117143216] 'agreement among raft nodes before linearized reading' (duration: 514.225796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:38.114182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:37.5997Z","time spent":"514.474693ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:38.114112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:37.714511Z","time spent":"399.597696ms","remote":"127.0.0.1:54912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:38.806657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477594,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T02:30:39.220952Z","caller":"traceutil/trace.go:171","msg":"trace[116582226] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"1.1020576s","start":"2026-08-07T02:30:38.118874Z","end":"2026-08-07T02:30:39.220932Z","steps":["trace[116582226] 'process raft request' (duration: 1.101927438s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.221081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.118856Z","time spent":"1.102162232s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:39.227426Z","caller":"traceutil/trace.go:171","msg":"trace[1833253099] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"842.405531ms","start":"2026-08-07T02:30:38.384943Z","end":"2026-08-07T02:30:39.227349Z","steps":["trace[1833253099] 'process raft request' (duration: 842.114145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.227614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.384922Z","time spent":"842.589304ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:30:39.228773Z","caller":"traceutil/trace.go:171","msg":"trace[776227027] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1189; }","duration":"922.727982ms","start":"2026-08-07T02:30:38.306024Z","end":"2026-08-07T02:30:39.228751Z","steps":["trace[776227027] 'read index received' (duration: 914.884585ms)","trace[776227027] 'applied index is now lower than readState.Index' (duration: 7.841997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:30:39.229007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.963637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-07T02:30:39.229044Z","caller":"traceutil/trace.go:171","msg":"trace[1054930555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1143; }","duration":"923.053049ms","start":"2026-08-07T02:30:38.305978Z","end":"2026-08-07T02:30:39.229031Z","steps":["trace[1054930555] 'agreement among raft nodes before linearized reading' (duration: 922.891666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.30596Z","time spent":"923.11095ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:39.229126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.7719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-07T02:30:39.229206Z","caller":"traceutil/trace.go:171","msg":"trace[297453640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1143; }","duration":"857.912222ms","start":"2026-08-07T02:30:38.371276Z","end":"2026-08-07T02:30:39.229188Z","steps":["trace[297453640] 'agreement among raft nodes before linearized reading' (duration: 857.681027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.371259Z","time spent":"857.980924ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-07T02:30:39.229403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.647247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:39.229445Z","caller":"traceutil/trace.go:171","msg":"trace[1146635647] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1143; }","duration":"396.692149ms","start":"2026-08-07T02:30:38.83274Z","end":"2026-08-07T02:30:39.229432Z","steps":["trace[1146635647] 'agreement among raft nodes before linearized reading' (duration: 396.626987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.832685Z","time spent":"396.78345ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:39.229507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.972476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-07T02:30:39.229542Z","caller":"traceutil/trace.go:171","msg":"trace[735508098] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1143; }","duration":"432.041847ms","start":"2026-08-07T02:30:38.79749Z","end":"2026-08-07T02:30:39.229531Z","steps":["trace[735508098] 'agreement among raft nodes before linearized reading' (duration: 431.975036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.797473Z","time spent":"432.106908ms","remote":"127.0.0.1:44902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:30:39.229595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.302113ms","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-08-07T02:30:39.229628Z","caller":"traceutil/trace.go:171","msg":"trace[1365733946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1143; }","duration":"608.372045ms","start":"2026-08-07T02:30:38.621245Z","end":"2026-08-07T02:30:39.229617Z","steps":["trace[1365733946] 'agreement among raft nodes before linearized reading' (duration: 608.281123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.621225Z","time spent":"608.419476ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-07T02:30:39.229675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.64968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:39.229707Z","caller":"traceutil/trace.go:171","msg":"trace[222596606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"110.709541ms","start":"2026-08-07T02:30:39.118988Z","end":"2026-08-07T02:30:39.229697Z","steps":["trace[222596606] 'agreement among raft nodes before linearized reading' (duration: 110.66303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.57711ms","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-08-07T02:30:39.229784Z","caller":"traceutil/trace.go:171","msg":"trace[1559039885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1143; }","duration":"607.664161ms","start":"2026-08-07T02:30:38.622103Z","end":"2026-08-07T02:30:39.229767Z","steps":["trace[1559039885] 'agreement among raft nodes before linearized reading' (duration: 607.446837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:39.229836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:30:38.622094Z","time spent":"607.725322ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:30:39.229846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.440327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:30:39.229877Z","caller":"traceutil/trace.go:171","msg":"trace[1640470288] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1143; }","duration":"137.525469ms","start":"2026-08-07T02:30:39.092343Z","end":"2026-08-07T02:30:39.229869Z","steps":["trace[1640470288] 'agreement among raft nodes before linearized reading' (duration: 137.475768ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:39.418809Z","caller":"traceutil/trace.go:171","msg":"trace[822644158] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"180.736936ms","start":"2026-08-07T02:30:39.23805Z","end":"2026-08-07T02:30:39.418787Z","steps":["trace[822644158] 'process raft request' (duration: 87.793612ms)","trace[822644158] 'compare' (duration: 92.807512ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:39.419013Z","caller":"traceutil/trace.go:171","msg":"trace[754741418] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"179.929429ms","start":"2026-08-07T02:30:39.239043Z","end":"2026-08-07T02:30:39.418972Z","steps":["trace[754741418] 'process raft request' (duration: 179.710075ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:41.394629Z","caller":"traceutil/trace.go:171","msg":"trace[1835260515] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1197; }","duration":"154.765474ms","start":"2026-08-07T02:30:41.239839Z","end":"2026-08-07T02:30:41.394604Z","steps":["trace[1835260515] 'read index received' (duration: 154.53822ms)","trace[1835260515] 'applied index is now lower than readState.Index' (duration: 226.304µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:41.394713Z","caller":"traceutil/trace.go:171","msg":"trace[393808226] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"164.44842ms","start":"2026-08-07T02:30:41.230219Z","end":"2026-08-07T02:30:41.394667Z","steps":["trace[393808226] 'process raft request' (duration: 164.238485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:41.394912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.182342ms","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-08-07T02:30:41.394961Z","caller":"traceutil/trace.go:171","msg":"trace[1473751872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"133.309275ms","start":"2026-08-07T02:30:41.261638Z","end":"2026-08-07T02:30:41.394947Z","steps":["trace[1473751872] 'agreement among raft nodes before linearized reading' (duration: 133.123501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:41.394959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.090302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-07T02:30:41.395049Z","caller":"traceutil/trace.go:171","msg":"trace[199812127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1149; }","duration":"155.232374ms","start":"2026-08-07T02:30:41.239795Z","end":"2026-08-07T02:30:41.395027Z","steps":["trace[199812127] 'agreement among raft nodes before linearized reading' (duration: 154.965079ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:41.592923Z","caller":"traceutil/trace.go:171","msg":"trace[1864581478] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"190.793157ms","start":"2026-08-07T02:30:41.402088Z","end":"2026-08-07T02:30:41.592881Z","steps":["trace[1864581478] 'process raft request' (duration: 160.53795ms)","trace[1864581478] 'compare' (duration: 29.86258ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:41.649505Z","caller":"traceutil/trace.go:171","msg":"trace[2089059226] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1198; }","duration":"227.665668ms","start":"2026-08-07T02:30:41.421815Z","end":"2026-08-07T02:30:41.649481Z","steps":["trace[2089059226] 'read index received' (duration: 140.901338ms)","trace[2089059226] 'applied index is now lower than readState.Index' (duration: 86.76343ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:41.64961Z","caller":"traceutil/trace.go:171","msg":"trace[444868336] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"246.158279ms","start":"2026-08-07T02:30:41.403442Z","end":"2026-08-07T02:30:41.6496Z","steps":["trace[444868336] 'process raft request' (duration: 245.913413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:41.649764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.930113ms","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-08-07T02:30:41.649796Z","caller":"traceutil/trace.go:171","msg":"trace[1872727491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1151; }","duration":"228.005854ms","start":"2026-08-07T02:30:41.421782Z","end":"2026-08-07T02:30:41.649788Z","steps":["trace[1872727491] 'agreement among raft nodes before linearized reading' (duration: 227.867292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:41.649821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.834341ms","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-08-07T02:30:41.649877Z","caller":"traceutil/trace.go:171","msg":"trace[215395486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1151; }","duration":"227.976694ms","start":"2026-08-07T02:30:41.421886Z","end":"2026-08-07T02:30:41.649863Z","steps":["trace[215395486] 'agreement among raft nodes before linearized reading' (duration: 227.7777ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:47.583627Z","caller":"traceutil/trace.go:171","msg":"trace[205406573] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"152.162363ms","start":"2026-08-07T02:30:47.431437Z","end":"2026-08-07T02:30:47.583599Z","steps":["trace[205406573] 'process raft request' (duration: 151.925348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:30:47.780846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.370464ms","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-08-07T02:30:47.78101Z","caller":"traceutil/trace.go:171","msg":"trace[1275467959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"100.552377ms","start":"2026-08-07T02:30:47.680397Z","end":"2026-08-07T02:30:47.780949Z","steps":["trace[1275467959] 'agreement among raft nodes before linearized reading' (duration: 99.373643ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:47.785534Z","caller":"traceutil/trace.go:171","msg":"trace[1552976992] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"106.993206ms","start":"2026-08-07T02:30:47.678504Z","end":"2026-08-07T02:30:47.785497Z","steps":["trace[1552976992] 'process raft request' (duration: 101.156159ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:50.030411Z","caller":"traceutil/trace.go:171","msg":"trace[2016593371] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"180.613174ms","start":"2026-08-07T02:30:49.849771Z","end":"2026-08-07T02:30:50.030384Z","steps":["trace[2016593371] 'process raft request' (duration: 180.561463ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:50.030657Z","caller":"traceutil/trace.go:171","msg":"trace[857929717] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"181.025682ms","start":"2026-08-07T02:30:49.849619Z","end":"2026-08-07T02:30:50.030645Z","steps":["trace[857929717] 'process raft request' (duration: 144.359216ms)","trace[857929717] 'compare' (duration: 36.212657ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:30:57.955902Z","caller":"traceutil/trace.go:171","msg":"trace[1032258033] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"131.156857ms","start":"2026-08-07T02:30:57.824715Z","end":"2026-08-07T02:30:57.955872Z","steps":["trace[1032258033] 'process raft request' (duration: 130.932003ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:30:57.959203Z","caller":"traceutil/trace.go:171","msg":"trace[2114465011] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1222; }","duration":"103.798321ms","start":"2026-08-07T02:30:57.855326Z","end":"2026-08-07T02:30:57.959124Z","steps":["trace[2114465011] 'process raft request' (duration: 103.709799ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:02.420034Z","caller":"traceutil/trace.go:171","msg":"trace[2018693503] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"113.631627ms","start":"2026-08-07T02:31:02.306353Z","end":"2026-08-07T02:31:02.419985Z","steps":["trace[2018693503] 'process raft request' (duration: 113.177108ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:04.678045Z","caller":"traceutil/trace.go:171","msg":"trace[616916367] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"251.59428ms","start":"2026-08-07T02:31:04.42642Z","end":"2026-08-07T02:31:04.678015Z","steps":["trace[616916367] 'process raft request' (duration: 251.355756ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:05.278356Z","caller":"traceutil/trace.go:171","msg":"trace[1454512566] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"131.767519ms","start":"2026-08-07T02:31:05.146564Z","end":"2026-08-07T02:31:05.278331Z","steps":["trace[1454512566] 'read index received' (duration: 131.523633ms)","trace[1454512566] 'applied index is now lower than readState.Index' (duration: 230.555µs)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:31:05.278476Z","caller":"traceutil/trace.go:171","msg":"trace[1790765971] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"155.582044ms","start":"2026-08-07T02:31:05.122879Z","end":"2026-08-07T02:31:05.278461Z","steps":["trace[1790765971] 'process raft request' (duration: 155.274108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:05.2787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.129406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-07T02:31:05.278743Z","caller":"traceutil/trace.go:171","msg":"trace[527329828] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1290; }","duration":"132.224978ms","start":"2026-08-07T02:31:05.146509Z","end":"2026-08-07T02:31:05.278734Z","steps":["trace[527329828] 'agreement among raft nodes before linearized reading' (duration: 132.082676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:05.483888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.966312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:31:05.483995Z","caller":"traceutil/trace.go:171","msg":"trace[1384001035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"132.132807ms","start":"2026-08-07T02:31:05.351841Z","end":"2026-08-07T02:31:05.483974Z","steps":["trace[1384001035] 'range keys from in-memory index tree' (duration: 131.864561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:06.224763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477902,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-07T02:31:06.332213Z","caller":"traceutil/trace.go:171","msg":"trace[717866480] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"626.239985ms","start":"2026-08-07T02:31:05.705946Z","end":"2026-08-07T02:31:06.332186Z","steps":["trace[717866480] 'process raft request' (duration: 626.080302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:06.332384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:05.705921Z","time spent":"626.372327ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-07T02:31:06.503817Z","caller":"traceutil/trace.go:171","msg":"trace[1054232790] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1346; }","duration":"779.227218ms","start":"2026-08-07T02:31:05.724556Z","end":"2026-08-07T02:31:06.503783Z","steps":["trace[1054232790] 'read index received' (duration: 607.541182ms)","trace[1054232790] 'applied index is now lower than readState.Index' (duration: 171.684986ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:31:06.503977Z","caller":"traceutil/trace.go:171","msg":"trace[1543553395] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"302.494316ms","start":"2026-08-07T02:31:06.201468Z","end":"2026-08-07T02:31:06.503962Z","steps":["trace[1543553395] 'process raft request' (duration: 251.150871ms)","trace[1543553395] 'compare' (duration: 51.026019ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:31:06.504117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.201439Z","time spent":"302.574637ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:31:06.504214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.428504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-07T02:31:06.50423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.621375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-07T02:31:06.504231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.158236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:31:06.504289Z","caller":"traceutil/trace.go:171","msg":"trace[552311488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"366.732427ms","start":"2026-08-07T02:31:06.137543Z","end":"2026-08-07T02:31:06.504276Z","steps":["trace[552311488] 'agreement among raft nodes before linearized reading' (duration: 366.557614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:06.504335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.137519Z","time spent":"366.807939ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-07T02:31:06.504334Z","caller":"traceutil/trace.go:171","msg":"trace[1102169997] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1293; }","duration":"144.32685ms","start":"2026-08-07T02:31:06.359985Z","end":"2026-08-07T02:31:06.504312Z","steps":["trace[1102169997] 'agreement among raft nodes before linearized reading' (duration: 144.114605ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:06.504277Z","caller":"traceutil/trace.go:171","msg":"trace[90710462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"509.547407ms","start":"2026-08-07T02:31:05.994713Z","end":"2026-08-07T02:31:06.50426Z","steps":["trace[90710462] 'agreement among raft nodes before linearized reading' (duration: 509.362603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:06.504374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.533692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-07T02:31:06.5044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.8084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-07T02:31:06.504445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:05.994695Z","time spent":"509.738891ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-07T02:31:06.504476Z","caller":"traceutil/trace.go:171","msg":"trace[1964558828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"779.927951ms","start":"2026-08-07T02:31:05.724532Z","end":"2026-08-07T02:31:06.50446Z","steps":["trace[1964558828] 'agreement among raft nodes before linearized reading' (duration: 779.696867ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:06.504479Z","caller":"traceutil/trace.go:171","msg":"trace[60495963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"374.699365ms","start":"2026-08-07T02:31:06.129753Z","end":"2026-08-07T02:31:06.504453Z","steps":["trace[60495963] 'agreement among raft nodes before linearized reading' (duration: 374.43446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:06.504513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:05.724522Z","time spent":"779.983512ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-07T02:31:06.504533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.129716Z","time spent":"374.806418ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-07T02:31:06.844841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.969728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:31:06.845039Z","caller":"traceutil/trace.go:171","msg":"trace[907108003] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"336.312981ms","start":"2026-08-07T02:31:06.5087Z","end":"2026-08-07T02:31:06.845013Z","steps":["trace[907108003] 'process raft request' (duration: 108.081677ms)","trace[907108003] 'compare' (duration: 227.823575ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:31:06.845068Z","caller":"traceutil/trace.go:171","msg":"trace[1323108449] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1348; }","duration":"267.740203ms","start":"2026-08-07T02:31:06.577312Z","end":"2026-08-07T02:31:06.845053Z","steps":["trace[1323108449] 'read index received' (duration: 11.374698ms)","trace[1323108449] 'applied index is now lower than readState.Index' (duration: 256.364325ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:31:06.845124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.508685Z","time spent":"336.398963ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:31:06.845215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.912176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-08-07T02:31:06.845244Z","caller":"traceutil/trace.go:171","msg":"trace[460824622] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1297; }","duration":"267.941506ms","start":"2026-08-07T02:31:06.577292Z","end":"2026-08-07T02:31:06.845233Z","steps":["trace[460824622] 'agreement among raft nodes before linearized reading' (duration: 267.805924ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:06.845296Z","caller":"traceutil/trace.go:171","msg":"trace[1160153627] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"335.044075ms","start":"2026-08-07T02:31:06.510232Z","end":"2026-08-07T02:31:06.845276Z","steps":["trace[1160153627] 'process raft request' (duration: 334.77832ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:31:06.845317Z","caller":"traceutil/trace.go:171","msg":"trace[1706755692] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"335.680658ms","start":"2026-08-07T02:31:06.509589Z","end":"2026-08-07T02:31:06.845269Z","steps":["trace[1706755692] 'process raft request' (duration: 335.360132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:06.845377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.510223Z","time spent":"335.111547ms","remote":"127.0.0.1:45046","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-08-07T02:31:06.845493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.509577Z","time spent":"335.81478ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:31:07.811801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T02:31:08.312813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281248970129477912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-07T02:31:08.389172Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.704698523s","expected-duration":"1s"} {"level":"info","ts":"2026-08-07T02:31:08.390317Z","caller":"traceutil/trace.go:171","msg":"trace[51078575] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.705941708s","start":"2026-08-07T02:31:06.684346Z","end":"2026-08-07T02:31:08.390287Z","steps":["trace[51078575] 'process raft request' (duration: 1.705728974s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.390552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.684317Z","time spent":"1.70610532s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:31:08.856228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.878191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-07T02:31:08.856461Z","caller":"traceutil/trace.go:171","msg":"trace[1739707338] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1352; }","duration":"1.545763012s","start":"2026-08-07T02:31:07.310677Z","end":"2026-08-07T02:31:08.85644Z","steps":["trace[1739707338] 'read index received' (duration: 1.079003929s)","trace[1739707338] 'applied index is now lower than readState.Index' (duration: 466.757103ms)"],"step_count":2} {"level":"info","ts":"2026-08-07T02:31:08.856471Z","caller":"traceutil/trace.go:171","msg":"trace[237228532] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"2.006331162s","start":"2026-08-07T02:31:06.850114Z","end":"2026-08-07T02:31:08.856445Z","steps":["trace[237228532] 'process raft request' (duration: 1.663121684s)","trace[237228532] 'compare' (duration: 342.759619ms)"],"step_count":2} {"level":"warn","ts":"2026-08-07T02:31:08.856601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:06.850094Z","time spent":"2.006431833s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-07T02:31:08.8616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510722153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:31:08.861691Z","caller":"traceutil/trace.go:171","msg":"trace[1911653375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"1.510883096s","start":"2026-08-07T02:31:07.350782Z","end":"2026-08-07T02:31:08.861666Z","steps":["trace[1911653375] 'agreement among raft nodes before linearized reading' (duration: 1.510767684s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.861753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:07.350766Z","time spent":"1.510972718s","remote":"127.0.0.1:44822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-07T02:31:08.861875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5511938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-07T02:31:08.861919Z","caller":"traceutil/trace.go:171","msg":"trace[752224703] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1299; }","duration":"1.551296962s","start":"2026-08-07T02:31:07.310613Z","end":"2026-08-07T02:31:08.86191Z","steps":["trace[752224703] 'agreement among raft nodes before linearized reading' (duration: 1.545926075s)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.861942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:07.310589Z","time spent":"1.551346404s","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-07T02:31:08.862183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.531019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-07T02:31:08.862231Z","caller":"traceutil/trace.go:171","msg":"trace[387302152] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1299; }","duration":"247.59361ms","start":"2026-08-07T02:31:08.614626Z","end":"2026-08-07T02:31:08.86222Z","steps":["trace[387302152] 'agreement among raft nodes before linearized reading' (duration: 247.413037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.862253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.408005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:31:08.862352Z","caller":"traceutil/trace.go:171","msg":"trace[1666028311] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1299; }","duration":"703.569247ms","start":"2026-08-07T02:31:08.158762Z","end":"2026-08-07T02:31:08.862332Z","steps":["trace[1666028311] 'agreement among raft nodes before linearized reading' (duration: 703.375114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.8624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:08.158748Z","time spent":"703.640919ms","remote":"127.0.0.1:44944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:31:08.862267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.458661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-08-07T02:31:08.862468Z","caller":"traceutil/trace.go:171","msg":"trace[955418121] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1299; }","duration":"812.702506ms","start":"2026-08-07T02:31:08.049745Z","end":"2026-08-07T02:31:08.862448Z","steps":["trace[955418121] 'agreement among raft nodes before linearized reading' (duration: 812.40802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.862497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:08.049715Z","time spent":"812.774637ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-08-07T02:31:08.862567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.582658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-07T02:31:08.8626Z","caller":"traceutil/trace.go:171","msg":"trace[218652277] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1299; }","duration":"725.660409ms","start":"2026-08-07T02:31:08.136932Z","end":"2026-08-07T02:31:08.862592Z","steps":["trace[218652277] 'agreement among raft nodes before linearized reading' (duration: 725.606668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.862622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:08.13691Z","time spent":"725.707071ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-08-07T02:31:08.862666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.161979ms","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-08-07T02:31:08.862709Z","caller":"traceutil/trace.go:171","msg":"trace[1758185635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"526.24943ms","start":"2026-08-07T02:31:08.336449Z","end":"2026-08-07T02:31:08.862699Z","steps":["trace[1758185635] 'agreement among raft nodes before linearized reading' (duration: 526.096167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:31:08.862714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.63201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-07T02:31:08.862774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-07T02:31:08.336427Z","time spent":"526.340652ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-07T02:31:08.862808Z","caller":"traceutil/trace.go:171","msg":"trace[595380281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"268.823604ms","start":"2026-08-07T02:31:08.593968Z","end":"2026-08-07T02:31:08.862792Z","steps":["trace[595380281] 'agreement among raft nodes before linearized reading' (duration: 268.573299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:34:04.078012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.157864ms","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-08-07T02:34:04.078106Z","caller":"traceutil/trace.go:171","msg":"trace[432055571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2127; }","duration":"255.275106ms","start":"2026-08-07T02:34:03.822812Z","end":"2026-08-07T02:34:04.078087Z","steps":["trace[432055571] 'range keys from in-memory index tree' (duration: 255.067352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-07T02:34:04.078164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.078812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-07T02:34:04.078216Z","caller":"traceutil/trace.go:171","msg":"trace[1995493369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2127; }","duration":"257.365568ms","start":"2026-08-07T02:34:03.820836Z","end":"2026-08-07T02:34:04.078201Z","steps":["trace[1995493369] 'range keys from in-memory index tree' (duration: 256.899088ms)"],"step_count":1} {"level":"info","ts":"2026-08-07T02:34:07.08629Z","caller":"traceutil/trace.go:171","msg":"trace[425261752] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"104.377059ms","start":"2026-08-07T02:34:06.981886Z","end":"2026-08-07T02:34:07.086263Z","steps":["trace[425261752] 'process raft request' (duration: 104.231187ms)"],"step_count":1}