{"level":"warn","ts":"2026-02-10T21:28:53.4217Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T21:28:53.421785Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-02-10T21:28:53.421871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T21:28:53.421918Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-02-10T21:28:53.421943Z","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-02-10T21:28:53.423592Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-02-10T21:28:53.423751Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-02-10T21:28:53.428027Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.991208ms"} {"level":"info","ts":"2026-02-10T21:28:53.4399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-02-10T21:28:53.439968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:28:53.440011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-02-10T21:28:53.440077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:28:53.440126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-02-10T21:28:53.440217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-02-10T21:28:53.447385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:28:53.456788Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:28:53.461556Z","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-02-10T21:28:53.466569Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:28:53.46757Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:28:53.467988Z","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-02-10T21:28:53.468039Z","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-02-10T21:28:53.468065Z","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-02-10T21:28:53.468298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-02-10T21:28:53.468985Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-02-10T21:28:53.478752Z","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-02-10T21:28:53.478904Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-02-10T21:28:53.47896Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-02-10T21:28:53.47917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:28:53.479197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:28:54.141338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:28:54.14138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:28:54.141407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-02-10T21:28:54.141418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:28:54.141422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-02-10T21:28:54.141434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-02-10T21:28:54.141441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-02-10T21:28:54.144661Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:28:54.144794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:28:54.145068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:28:54.145377Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:28:54.145392Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:28:54.145861Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:54.146614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-02-10T21:28:54.147599Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:54.147666Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:54.147723Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:28:54.154571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T21:29:14.003256Z","caller":"traceutil/trace.go:171","msg":"trace[403010163] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"178.720331ms","start":"2026-02-10T21:29:13.82452Z","end":"2026-02-10T21:29:14.00324Z","steps":["trace[403010163] 'read index received' (duration: 178.583948ms)","trace[403010163] 'applied index is now lower than readState.Index' (duration: 135.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:14.003338Z","caller":"traceutil/trace.go:171","msg":"trace[642259966] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"183.923128ms","start":"2026-02-10T21:29:13.819409Z","end":"2026-02-10T21:29:14.003332Z","steps":["trace[642259966] 'process raft request' (duration: 183.736694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:14.003527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.992747ms","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-02-10T21:29:14.003565Z","caller":"traceutil/trace.go:171","msg":"trace[349756432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"179.059169ms","start":"2026-02-10T21:29:13.8245Z","end":"2026-02-10T21:29:14.003559Z","steps":["trace[349756432] 'agreement among raft nodes before linearized reading' (duration: 178.993057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:14.003643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.36207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:14.003662Z","caller":"traceutil/trace.go:171","msg":"trace[947716360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"142.39282ms","start":"2026-02-10T21:29:13.861264Z","end":"2026-02-10T21:29:14.003656Z","steps":["trace[947716360] 'agreement among raft nodes before linearized reading' (duration: 142.367459ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:14.200761Z","caller":"traceutil/trace.go:171","msg":"trace[2106053842] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"112.291627ms","start":"2026-02-10T21:29:14.088424Z","end":"2026-02-10T21:29:14.200716Z","steps":["trace[2106053842] 'process raft request' (duration: 112.111453ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:23.338797Z","caller":"traceutil/trace.go:171","msg":"trace[1648231020] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"287.659124ms","start":"2026-02-10T21:29:23.051117Z","end":"2026-02-10T21:29:23.338776Z","steps":["trace[1648231020] 'process raft request' (duration: 287.459739ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:24.221991Z","caller":"traceutil/trace.go:171","msg":"trace[1793050633] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"164.291859ms","start":"2026-02-10T21:29:24.057673Z","end":"2026-02-10T21:29:24.221964Z","steps":["trace[1793050633] 'read index received' (duration: 164.139285ms)","trace[1793050633] 'applied index is now lower than readState.Index' (duration: 151.774µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:24.222069Z","caller":"traceutil/trace.go:171","msg":"trace[416264894] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"174.985678ms","start":"2026-02-10T21:29:24.047068Z","end":"2026-02-10T21:29:24.222053Z","steps":["trace[416264894] 'process raft request' (duration: 174.793224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:24.222235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.548725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T21:29:24.222253Z","caller":"traceutil/trace.go:171","msg":"trace[629629560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"164.588235ms","start":"2026-02-10T21:29:24.05766Z","end":"2026-02-10T21:29:24.222248Z","steps":["trace[629629560] 'agreement among raft nodes before linearized reading' (duration: 164.497003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:24.222347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.374ms","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-02-10T21:29:24.222371Z","caller":"traceutil/trace.go:171","msg":"trace[1988006944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"159.3968ms","start":"2026-02-10T21:29:24.06296Z","end":"2026-02-10T21:29:24.222356Z","steps":["trace[1988006944] 'agreement among raft nodes before linearized reading' (duration: 159.359119ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:24.439652Z","caller":"traceutil/trace.go:171","msg":"trace[1789220862] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"208.511866ms","start":"2026-02-10T21:29:24.231096Z","end":"2026-02-10T21:29:24.439608Z","steps":["trace[1789220862] 'read index received' (duration: 15.379333ms)","trace[1789220862] 'applied index is now lower than readState.Index' (duration: 193.131613ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:24.440825Z","caller":"traceutil/trace.go:171","msg":"trace[530976445] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"211.574806ms","start":"2026-02-10T21:29:24.22893Z","end":"2026-02-10T21:29:24.440505Z","steps":["trace[530976445] 'process raft request' (duration: 208.912256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:24.44152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.407938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-10T21:29:24.441563Z","caller":"traceutil/trace.go:171","msg":"trace[1605088598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"210.461719ms","start":"2026-02-10T21:29:24.231078Z","end":"2026-02-10T21:29:24.44154Z","steps":["trace[1605088598] 'agreement among raft nodes before linearized reading' (duration: 210.327676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:26.889945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.346552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:26.890066Z","caller":"traceutil/trace.go:171","msg":"trace[2090524015] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"322.187505ms","start":"2026-02-10T21:29:26.567867Z","end":"2026-02-10T21:29:26.890055Z","steps":["trace[2090524015] 'process raft request' (duration: 322.146364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:26.890107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:26.567848Z","time spent":"322.238716ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:29:26.890429Z","caller":"traceutil/trace.go:171","msg":"trace[1258225202] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"441.404887ms","start":"2026-02-10T21:29:26.448996Z","end":"2026-02-10T21:29:26.890401Z","steps":["trace[1258225202] 'read index received' (duration: 241.308349ms)","trace[1258225202] 'applied index is now lower than readState.Index' (duration: 200.094257ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:26.890556Z","caller":"traceutil/trace.go:171","msg":"trace[416192092] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"621.08557ms","start":"2026-02-10T21:29:26.269457Z","end":"2026-02-10T21:29:26.890543Z","steps":["trace[416192092] 'process raft request' (duration: 420.658864ms)","trace[416192092] 'compare' (duration: 199.197469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:26.890615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.490986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-10T21:29:26.890641Z","caller":"traceutil/trace.go:171","msg":"trace[1426054378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"431.537207ms","start":"2026-02-10T21:29:26.459096Z","end":"2026-02-10T21:29:26.890634Z","steps":["trace[1426054378] 'agreement among raft nodes before linearized reading' (duration: 431.455085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:26.890662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:26.459087Z","time spent":"431.570947ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:29:26.890657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:26.269437Z","time spent":"621.146532ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:29:26.890773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.790846ms","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-02-10T21:29:26.890791Z","caller":"traceutil/trace.go:171","msg":"trace[381963277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"441.855858ms","start":"2026-02-10T21:29:26.44893Z","end":"2026-02-10T21:29:26.890786Z","steps":["trace[381963277] 'agreement among raft nodes before linearized reading' (duration: 441.805737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:26.890809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:26.448912Z","time spent":"441.891068ms","remote":"127.0.0.1:45538","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-02-10T21:29:27.027913Z","caller":"traceutil/trace.go:171","msg":"trace[844941886] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"129.408149ms","start":"2026-02-10T21:29:26.898475Z","end":"2026-02-10T21:29:27.027883Z","steps":["trace[844941886] 'process raft request' (duration: 112.755798ms)","trace[844941886] 'compare' (duration: 16.528789ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:30.07472Z","caller":"traceutil/trace.go:171","msg":"trace[447147883] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"213.084968ms","start":"2026-02-10T21:29:29.861611Z","end":"2026-02-10T21:29:30.074696Z","steps":["trace[447147883] 'read index received' (duration: 212.927515ms)","trace[447147883] 'applied index is now lower than readState.Index' (duration: 156.503µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:30.074855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.221431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:30.074889Z","caller":"traceutil/trace.go:171","msg":"trace[1830630666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"213.309743ms","start":"2026-02-10T21:29:29.861567Z","end":"2026-02-10T21:29:30.074877Z","steps":["trace[1830630666] 'agreement among raft nodes before linearized reading' (duration: 213.220581ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:30.074921Z","caller":"traceutil/trace.go:171","msg":"trace[119009048] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"348.752579ms","start":"2026-02-10T21:29:29.726149Z","end":"2026-02-10T21:29:30.074901Z","steps":["trace[119009048] 'process raft request' (duration: 348.412731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:30.07501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:29.726133Z","time spent":"348.82871ms","remote":"127.0.0.1:45538","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-02-10T21:29:33.613707Z","caller":"traceutil/trace.go:171","msg":"trace[785454559] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"218.707373ms","start":"2026-02-10T21:29:33.394978Z","end":"2026-02-10T21:29:33.613685Z","steps":["trace[785454559] 'process raft request' (duration: 192.942408ms)","trace[785454559] 'compare' (duration: 25.398437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:33.614459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.529738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.614525Z","caller":"traceutil/trace.go:171","msg":"trace[366653836] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:514; }","duration":"213.62497ms","start":"2026-02-10T21:29:33.400882Z","end":"2026-02-10T21:29:33.614507Z","steps":["trace[366653836] 'agreement among raft nodes before linearized reading' (duration: 213.517138ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:33.614283Z","caller":"traceutil/trace.go:171","msg":"trace[669231458] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"212.568067ms","start":"2026-02-10T21:29:33.400903Z","end":"2026-02-10T21:29:33.613471Z","steps":["trace[669231458] 'read index received' (duration: 187.028737ms)","trace[669231458] 'applied index is now lower than readState.Index' (duration: 25.53823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:29:33.615515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.24317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.61556Z","caller":"traceutil/trace.go:171","msg":"trace[2141208063] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"212.287951ms","start":"2026-02-10T21:29:33.403258Z","end":"2026-02-10T21:29:33.615546Z","steps":["trace[2141208063] 'agreement among raft nodes before linearized reading' (duration: 212.21307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:33.615869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.80949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.615911Z","caller":"traceutil/trace.go:171","msg":"trace[1415207783] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"207.858771ms","start":"2026-02-10T21:29:33.408041Z","end":"2026-02-10T21:29:33.615899Z","steps":["trace[1415207783] 'agreement among raft nodes before linearized reading' (duration: 207.80767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:33.61602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.455082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.616052Z","caller":"traceutil/trace.go:171","msg":"trace[1746016792] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"211.495433ms","start":"2026-02-10T21:29:33.404549Z","end":"2026-02-10T21:29:33.616044Z","steps":["trace[1746016792] 'agreement among raft nodes before linearized reading' (duration: 211.452792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:33.61653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.549594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.616572Z","caller":"traceutil/trace.go:171","msg":"trace[58519528] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"215.598185ms","start":"2026-02-10T21:29:33.400964Z","end":"2026-02-10T21:29:33.616563Z","steps":["trace[58519528] 'agreement among raft nodes before linearized reading' (duration: 215.539283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:33.616835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.8404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.616874Z","caller":"traceutil/trace.go:171","msg":"trace[1932858713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"215.887171ms","start":"2026-02-10T21:29:33.400977Z","end":"2026-02-10T21:29:33.616864Z","steps":["trace[1932858713] 'agreement among raft nodes before linearized reading' (duration: 215.82385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:33.617281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.3132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.617358Z","caller":"traceutil/trace.go:171","msg":"trace[1590711224] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"216.396382ms","start":"2026-02-10T21:29:33.400952Z","end":"2026-02-10T21:29:33.617348Z","steps":["trace[1590711224] 'agreement among raft nodes before linearized reading' (duration: 216.30335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:33.617633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.677398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:33.617723Z","caller":"traceutil/trace.go:171","msg":"trace[1644722956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"216.773592ms","start":"2026-02-10T21:29:33.400938Z","end":"2026-02-10T21:29:33.617711Z","steps":["trace[1644722956] 'agreement among raft nodes before linearized reading' (duration: 214.48186ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:39.095312Z","caller":"traceutil/trace.go:171","msg":"trace[1797166906] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"100.912033ms","start":"2026-02-10T21:29:38.994374Z","end":"2026-02-10T21:29:39.095286Z","steps":["trace[1797166906] 'process raft request' (duration: 95.220166ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:44.586516Z","caller":"traceutil/trace.go:171","msg":"trace[1765556941] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"114.458726ms","start":"2026-02-10T21:29:44.472042Z","end":"2026-02-10T21:29:44.586501Z","steps":["trace[1765556941] 'read index received' (duration: 7.909807ms)","trace[1765556941] 'applied index is now lower than readState.Index' (duration: 106.548429ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:44.586532Z","caller":"traceutil/trace.go:171","msg":"trace[2132041986] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"120.021181ms","start":"2026-02-10T21:29:44.466496Z","end":"2026-02-10T21:29:44.586517Z","steps":["trace[2132041986] 'process raft request' (duration: 91.361951ms)","trace[2132041986] 'compare' (duration: 28.521657ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:44.586649Z","caller":"traceutil/trace.go:171","msg":"trace[979752845] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"109.327451ms","start":"2026-02-10T21:29:44.477313Z","end":"2026-02-10T21:29:44.586641Z","steps":["trace[979752845] 'process raft request' (duration: 109.149507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:44.586791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.733442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T21:29:44.586809Z","caller":"traceutil/trace.go:171","msg":"trace[1731655671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"114.781103ms","start":"2026-02-10T21:29:44.472023Z","end":"2026-02-10T21:29:44.586804Z","steps":["trace[1731655671] 'agreement among raft nodes before linearized reading' (duration: 114.703311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:48.353494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.453078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:48.353584Z","caller":"traceutil/trace.go:171","msg":"trace[1860760069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"492.56192ms","start":"2026-02-10T21:29:47.860974Z","end":"2026-02-10T21:29:48.353536Z","steps":["trace[1860760069] 'range keys from in-memory index tree' (duration: 492.337605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:48.353615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:47.86095Z","time spent":"492.659052ms","remote":"127.0.0.1:45284","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-02-10T21:29:48.980579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.482517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:29:48.980657Z","caller":"traceutil/trace.go:171","msg":"trace[1784292806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"118.60338ms","start":"2026-02-10T21:29:48.862035Z","end":"2026-02-10T21:29:48.980638Z","steps":["trace[1784292806] 'range keys from in-memory index tree' (duration: 118.395306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:48.98105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.764328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T21:29:48.981119Z","caller":"traceutil/trace.go:171","msg":"trace[544753552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"309.869931ms","start":"2026-02-10T21:29:48.671229Z","end":"2026-02-10T21:29:48.981099Z","steps":["trace[544753552] 'range keys from in-memory index tree' (duration: 309.623945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:48.981158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:48.671201Z","time spent":"309.949022ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T21:29:49.410585Z","caller":"traceutil/trace.go:171","msg":"trace[139978688] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"399.760488ms","start":"2026-02-10T21:29:49.010802Z","end":"2026-02-10T21:29:49.410562Z","steps":["trace[139978688] 'read index received' (duration: 399.598454ms)","trace[139978688] 'applied index is now lower than readState.Index' (duration: 161.284µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:49.410865Z","caller":"traceutil/trace.go:171","msg":"trace[1803623484] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"422.996606ms","start":"2026-02-10T21:29:48.987858Z","end":"2026-02-10T21:29:49.410854Z","steps":["trace[1803623484] 'process raft request' (duration: 422.595798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:49.410967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:48.987836Z","time spent":"423.058217ms","remote":"127.0.0.1:45538","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-02-10T21:29:49.411201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.406192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T21:29:49.411232Z","caller":"traceutil/trace.go:171","msg":"trace[1808571603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"400.466353ms","start":"2026-02-10T21:29:49.010756Z","end":"2026-02-10T21:29:49.411222Z","steps":["trace[1808571603] 'agreement among raft nodes before linearized reading' (duration: 400.363291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:49.411259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:49.01074Z","time spent":"400.512604ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T21:29:49.411434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.818065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-10T21:29:49.411461Z","caller":"traceutil/trace.go:171","msg":"trace[1665282187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"286.874786ms","start":"2026-02-10T21:29:49.124579Z","end":"2026-02-10T21:29:49.411454Z","steps":["trace[1665282187] 'agreement among raft nodes before linearized reading' (duration: 286.802614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:49.41166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.483888ms","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-02-10T21:29:49.411685Z","caller":"traceutil/trace.go:171","msg":"trace[306232589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"192.538208ms","start":"2026-02-10T21:29:49.21914Z","end":"2026-02-10T21:29:49.411678Z","steps":["trace[306232589] 'agreement among raft nodes before linearized reading' (duration: 192.470347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:29:49.686032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.69598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:29:49.686136Z","caller":"traceutil/trace.go:171","msg":"trace[517304047] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"267.189566ms","start":"2026-02-10T21:29:49.418927Z","end":"2026-02-10T21:29:49.686117Z","steps":["trace[517304047] 'process raft request' (duration: 101.385334ms)","trace[517304047] 'compare' (duration: 165.516836ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:29:49.688753Z","caller":"traceutil/trace.go:171","msg":"trace[809038992] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"269.710263ms","start":"2026-02-10T21:29:49.419024Z","end":"2026-02-10T21:29:49.688734Z","steps":["trace[809038992] 'process raft request' (duration: 269.384756ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:49.688934Z","caller":"traceutil/trace.go:171","msg":"trace[561400145] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"268.002895ms","start":"2026-02-10T21:29:49.420906Z","end":"2026-02-10T21:29:49.688909Z","steps":["trace[561400145] 'process raft request' (duration: 267.763259ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:51.571371Z","caller":"traceutil/trace.go:171","msg":"trace[1256576283] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"145.896648ms","start":"2026-02-10T21:29:51.425316Z","end":"2026-02-10T21:29:51.571212Z","steps":["trace[1256576283] 'process raft request' (duration: 145.780616ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:51.809212Z","caller":"traceutil/trace.go:171","msg":"trace[383103906] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"100.273549ms","start":"2026-02-10T21:29:51.708923Z","end":"2026-02-10T21:29:51.809197Z","steps":["trace[383103906] 'process raft request' (duration: 100.175577ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:51.811233Z","caller":"traceutil/trace.go:171","msg":"trace[708454271] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"102.053848ms","start":"2026-02-10T21:29:51.709168Z","end":"2026-02-10T21:29:51.811222Z","steps":["trace[708454271] 'process raft request' (duration: 101.936726ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:29:51.811399Z","caller":"traceutil/trace.go:171","msg":"trace[1796391833] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"101.927726ms","start":"2026-02-10T21:29:51.709463Z","end":"2026-02-10T21:29:51.81139Z","steps":["trace[1796391833] 'process raft request' (duration: 101.697821ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:00.080184Z","caller":"traceutil/trace.go:171","msg":"trace[1146825739] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"238.854694ms","start":"2026-02-10T21:29:59.841311Z","end":"2026-02-10T21:30:00.080165Z","steps":["trace[1146825739] 'read index received' (duration: 238.263021ms)","trace[1146825739] 'applied index is now lower than readState.Index' (duration: 590.973µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:00.080449Z","caller":"traceutil/trace.go:171","msg":"trace[1873185122] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"463.43105ms","start":"2026-02-10T21:29:59.61701Z","end":"2026-02-10T21:30:00.080441Z","steps":["trace[1873185122] 'process raft request' (duration: 462.606462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.080585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:29:59.616994Z","time spent":"463.527062ms","remote":"127.0.0.1:45538","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-02-10T21:30:00.080692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.720327ms","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-02-10T21:30:00.08075Z","caller":"traceutil/trace.go:171","msg":"trace[783743080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:708; }","duration":"232.810048ms","start":"2026-02-10T21:29:59.847924Z","end":"2026-02-10T21:30:00.080734Z","steps":["trace[783743080] 'agreement among raft nodes before linearized reading' (duration: 232.638985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.080849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.23334ms","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-02-10T21:30:00.080876Z","caller":"traceutil/trace.go:171","msg":"trace[686678475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:708; }","duration":"212.25864ms","start":"2026-02-10T21:29:59.868609Z","end":"2026-02-10T21:30:00.080868Z","steps":["trace[686678475] 'agreement among raft nodes before linearized reading' (duration: 212.160338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.080959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.657532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T21:30:00.080976Z","caller":"traceutil/trace.go:171","msg":"trace[1451378057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:708; }","duration":"239.703214ms","start":"2026-02-10T21:29:59.841266Z","end":"2026-02-10T21:30:00.08097Z","steps":["trace[1451378057] 'agreement among raft nodes before linearized reading' (duration: 239.657283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.080998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.797082ms","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-02-10T21:30:00.081021Z","caller":"traceutil/trace.go:171","msg":"trace[1684882918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:708; }","duration":"212.832533ms","start":"2026-02-10T21:29:59.868183Z","end":"2026-02-10T21:30:00.081016Z","steps":["trace[1684882918] 'agreement among raft nodes before linearized reading' (duration: 212.774311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.081075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.690046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:00.081091Z","caller":"traceutil/trace.go:171","msg":"trace[1677528584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"219.724046ms","start":"2026-02-10T21:29:59.86136Z","end":"2026-02-10T21:30:00.081085Z","steps":["trace[1677528584] 'agreement among raft nodes before linearized reading' (duration: 219.703046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.41117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.836073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:00.411271Z","caller":"traceutil/trace.go:171","msg":"trace[14663099] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"324.142429ms","start":"2026-02-10T21:30:00.087104Z","end":"2026-02-10T21:30:00.411246Z","steps":["trace[14663099] 'read index received' (duration: 141.232865ms)","trace[14663099] 'applied index is now lower than readState.Index' (duration: 182.908504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:00.411384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.272442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:00.411421Z","caller":"traceutil/trace.go:171","msg":"trace[67512388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"324.322773ms","start":"2026-02-10T21:30:00.087086Z","end":"2026-02-10T21:30:00.411408Z","steps":["trace[67512388] 'agreement among raft nodes before linearized reading' (duration: 324.21985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.411461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.087079Z","time spent":"324.371684ms","remote":"127.0.0.1:45286","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-02-10T21:30:00.411676Z","caller":"traceutil/trace.go:171","msg":"trace[58486942] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"324.686911ms","start":"2026-02-10T21:30:00.086975Z","end":"2026-02-10T21:30:00.411662Z","steps":["trace[58486942] 'process raft request' (duration: 141.254185ms)","trace[58486942] 'compare' (duration: 182.580907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:00.411739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.08696Z","time spent":"324.744012ms","remote":"127.0.0.1:45538","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-02-10T21:30:00.664181Z","caller":"traceutil/trace.go:171","msg":"trace[1463737331] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"570.118851ms","start":"2026-02-10T21:30:00.094047Z","end":"2026-02-10T21:30:00.664166Z","steps":["trace[1463737331] 'process raft request' (duration: 570.08868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.664484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.094029Z","time spent":"570.199612ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:00.664722Z","caller":"traceutil/trace.go:171","msg":"trace[676073257] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"576.443682ms","start":"2026-02-10T21:30:00.088269Z","end":"2026-02-10T21:30:00.664713Z","steps":["trace[676073257] 'process raft request' (duration: 491.486165ms)","trace[676073257] 'compare' (duration: 84.133559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:00.664766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.08826Z","time spent":"576.483873ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:00.664851Z","caller":"traceutil/trace.go:171","msg":"trace[1109544449] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"576.031733ms","start":"2026-02-10T21:30:00.088813Z","end":"2026-02-10T21:30:00.664845Z","steps":["trace[1109544449] 'process raft request' (duration: 575.263646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.664883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.088805Z","time spent":"576.059233ms","remote":"127.0.0.1:45538","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-02-10T21:30:00.664958Z","caller":"traceutil/trace.go:171","msg":"trace[603802465] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"574.289075ms","start":"2026-02-10T21:30:00.090664Z","end":"2026-02-10T21:30:00.664953Z","steps":["trace[603802465] 'process raft request' (duration: 573.440985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:00.664986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:00.090656Z","time spent":"574.315136ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:30:02.647641Z","caller":"traceutil/trace.go:171","msg":"trace[60641459] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"228.310738ms","start":"2026-02-10T21:30:02.419314Z","end":"2026-02-10T21:30:02.647625Z","steps":["trace[60641459] 'process raft request' (duration: 228.211986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:13.174193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.886441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:13.174236Z","caller":"traceutil/trace.go:171","msg":"trace[1266174577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"315.962342ms","start":"2026-02-10T21:30:12.858264Z","end":"2026-02-10T21:30:13.174226Z","steps":["trace[1266174577] 'range keys from in-memory index tree' (duration: 315.84442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:13.174257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:12.858251Z","time spent":"316.001534ms","remote":"127.0.0.1:45284","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-02-10T21:30:14.342307Z","caller":"traceutil/trace.go:171","msg":"trace[113340053] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"140.417487ms","start":"2026-02-10T21:30:14.201869Z","end":"2026-02-10T21:30:14.342287Z","steps":["trace[113340053] 'process raft request' (duration: 140.308145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.994784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.325257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T21:30:14.994856Z","caller":"traceutil/trace.go:171","msg":"trace[1980458900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"240.42163ms","start":"2026-02-10T21:30:14.754413Z","end":"2026-02-10T21:30:14.994835Z","steps":["trace[1980458900] 'range keys from in-memory index tree' (duration: 240.196154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.995309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.445051ms","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-02-10T21:30:14.99535Z","caller":"traceutil/trace.go:171","msg":"trace[2092403529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"222.504513ms","start":"2026-02-10T21:30:14.772835Z","end":"2026-02-10T21:30:14.99534Z","steps":["trace[2092403529] 'range keys from in-memory index tree' (duration: 222.351289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.995604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.19091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:14.995666Z","caller":"traceutil/trace.go:171","msg":"trace[679888642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"135.284432ms","start":"2026-02-10T21:30:14.860372Z","end":"2026-02-10T21:30:14.995657Z","steps":["trace[679888642] 'range keys from in-memory index tree' (duration: 135.093348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.995856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.746733ms","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-02-10T21:30:14.996974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.015208ms","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-02-10T21:30:14.997019Z","caller":"traceutil/trace.go:171","msg":"trace[1218192000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"314.10197ms","start":"2026-02-10T21:30:14.682907Z","end":"2026-02-10T21:30:14.997009Z","steps":["trace[1218192000] 'range keys from in-memory index tree' (duration: 313.836243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.997048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:14.682885Z","time spent":"314.155702ms","remote":"127.0.0.1:45538","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":"info","ts":"2026-02-10T21:30:14.997257Z","caller":"traceutil/trace.go:171","msg":"trace[1613075633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"251.174068ms","start":"2026-02-10T21:30:14.745824Z","end":"2026-02-10T21:30:14.996998Z","steps":["trace[1613075633] 'range keys from in-memory index tree' (duration: 248.527389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.996606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.626936ms","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-02-10T21:30:14.99752Z","caller":"traceutil/trace.go:171","msg":"trace[1497317255] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:748; }","duration":"468.672171ms","start":"2026-02-10T21:30:14.528836Z","end":"2026-02-10T21:30:14.997509Z","steps":["trace[1497317255] 'count revisions from in-memory index tree' (duration: 467.556704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.997568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:14.528825Z","time spent":"468.728992ms","remote":"127.0.0.1:45740","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-02-10T21:30:14.996643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.307948ms","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-02-10T21:30:14.997892Z","caller":"traceutil/trace.go:171","msg":"trace[825654742] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:748; }","duration":"505.586567ms","start":"2026-02-10T21:30:14.492294Z","end":"2026-02-10T21:30:14.997881Z","steps":["trace[825654742] 'count revisions from in-memory index tree' (duration: 504.221256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:14.998134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:14.492275Z","time spent":"505.839712ms","remote":"127.0.0.1:45438","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":"info","ts":"2026-02-10T21:30:15.165279Z","caller":"traceutil/trace.go:171","msg":"trace[1520458711] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"157.205659ms","start":"2026-02-10T21:30:15.008056Z","end":"2026-02-10T21:30:15.165262Z","steps":["trace[1520458711] 'process raft request' (duration: 157.151718ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:15.16647Z","caller":"traceutil/trace.go:171","msg":"trace[616922199] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"160.634904ms","start":"2026-02-10T21:30:15.005825Z","end":"2026-02-10T21:30:15.16646Z","steps":["trace[616922199] 'process raft request' (duration: 159.236943ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:15.166684Z","caller":"traceutil/trace.go:171","msg":"trace[1953253662] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"160.360388ms","start":"2026-02-10T21:30:15.006315Z","end":"2026-02-10T21:30:15.166676Z","steps":["trace[1953253662] 'process raft request' (duration: 158.864585ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:27.515021Z","caller":"traceutil/trace.go:171","msg":"trace[1911659185] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"307.208169ms","start":"2026-02-10T21:30:27.207797Z","end":"2026-02-10T21:30:27.515005Z","steps":["trace[1911659185] 'read index received' (duration: 307.027745ms)","trace[1911659185] 'applied index is now lower than readState.Index' (duration: 179.824µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:27.515103Z","caller":"traceutil/trace.go:171","msg":"trace[2104375955] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"341.851916ms","start":"2026-02-10T21:30:27.173245Z","end":"2026-02-10T21:30:27.515097Z","steps":["trace[2104375955] 'process raft request' (duration: 341.666292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:27.515177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:27.173213Z","time spent":"341.908847ms","remote":"127.0.0.1:45538","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-02-10T21:30:27.515341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.772781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-10T21:30:27.515386Z","caller":"traceutil/trace.go:171","msg":"trace[976589403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:834; }","duration":"298.837813ms","start":"2026-02-10T21:30:27.216536Z","end":"2026-02-10T21:30:27.515374Z","steps":["trace[976589403] 'agreement among raft nodes before linearized reading' (duration: 298.71134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:27.515502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.879745ms","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-02-10T21:30:27.51551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.833681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T21:30:27.515529Z","caller":"traceutil/trace.go:171","msg":"trace[1970342651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:834; }","duration":"297.858931ms","start":"2026-02-10T21:30:27.217664Z","end":"2026-02-10T21:30:27.515523Z","steps":["trace[1970342651] 'agreement among raft nodes before linearized reading' (duration: 297.81637ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:27.515529Z","caller":"traceutil/trace.go:171","msg":"trace[1781148446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"289.914975ms","start":"2026-02-10T21:30:27.225606Z","end":"2026-02-10T21:30:27.515521Z","steps":["trace[1781148446] 'agreement among raft nodes before linearized reading' (duration: 289.824903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:27.515608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.810492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T21:30:27.51563Z","caller":"traceutil/trace.go:171","msg":"trace[1297099713] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:834; }","duration":"307.851703ms","start":"2026-02-10T21:30:27.207772Z","end":"2026-02-10T21:30:27.515623Z","steps":["trace[1297099713] 'agreement among raft nodes before linearized reading' (duration: 307.801572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:27.515645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:27.207759Z","time spent":"307.882764ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"info","ts":"2026-02-10T21:30:27.796519Z","caller":"traceutil/trace.go:171","msg":"trace[1564458605] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"275.298373ms","start":"2026-02-10T21:30:27.521159Z","end":"2026-02-10T21:30:27.796458Z","steps":["trace[1564458605] 'process raft request' (duration: 275.278473ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:27.796622Z","caller":"traceutil/trace.go:171","msg":"trace[822142310] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"276.591452ms","start":"2026-02-10T21:30:27.520016Z","end":"2026-02-10T21:30:27.796607Z","steps":["trace[822142310] 'process raft request' (duration: 276.330306ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:27.796759Z","caller":"traceutil/trace.go:171","msg":"trace[2004647539] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"276.701534ms","start":"2026-02-10T21:30:27.520051Z","end":"2026-02-10T21:30:27.796752Z","steps":["trace[2004647539] 'process raft request' (duration: 276.368247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:28.277418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.793691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T21:30:28.277558Z","caller":"traceutil/trace.go:171","msg":"trace[1021250603] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"418.348797ms","start":"2026-02-10T21:30:27.859196Z","end":"2026-02-10T21:30:28.277545Z","steps":["trace[1021250603] 'read index received' (duration: 293.330351ms)","trace[1021250603] 'applied index is now lower than readState.Index' (duration: 125.017736ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:28.277608Z","caller":"traceutil/trace.go:171","msg":"trace[2017277341] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"479.088512ms","start":"2026-02-10T21:30:27.798511Z","end":"2026-02-10T21:30:28.277599Z","steps":["trace[2017277341] 'process raft request' (duration: 354.056726ms)","trace[2017277341] 'compare' (duration: 124.649128ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:28.277645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:27.798499Z","time spent":"479.129163ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:30:28.277883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.685394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:28.277903Z","caller":"traceutil/trace.go:171","msg":"trace[328802230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"418.727476ms","start":"2026-02-10T21:30:27.859171Z","end":"2026-02-10T21:30:28.277899Z","steps":["trace[328802230] 'agreement among raft nodes before linearized reading' (duration: 418.402778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:28.277919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:27.859158Z","time spent":"418.756226ms","remote":"127.0.0.1:45284","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-02-10T21:30:28.883029Z","caller":"traceutil/trace.go:171","msg":"trace[1109983187] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"175.711248ms","start":"2026-02-10T21:30:28.707305Z","end":"2026-02-10T21:30:28.883016Z","steps":["trace[1109983187] 'process raft request' (duration: 175.379861ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:36.11823Z","caller":"traceutil/trace.go:171","msg":"trace[127704629] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:890; }","duration":"287.556534ms","start":"2026-02-10T21:30:35.830658Z","end":"2026-02-10T21:30:36.118214Z","steps":["trace[127704629] 'read index received' (duration: 40.531137ms)","trace[127704629] 'applied index is now lower than readState.Index' (duration: 247.024877ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:36.1183Z","caller":"traceutil/trace.go:171","msg":"trace[20431844] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"296.169304ms","start":"2026-02-10T21:30:35.822126Z","end":"2026-02-10T21:30:36.118295Z","steps":["trace[20431844] 'process raft request' (duration: 295.99806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:36.118443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.770528ms","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-02-10T21:30:36.118458Z","caller":"traceutil/trace.go:171","msg":"trace[336224354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"287.808459ms","start":"2026-02-10T21:30:35.830645Z","end":"2026-02-10T21:30:36.118454Z","steps":["trace[336224354] 'agreement among raft nodes before linearized reading' (duration: 287.733607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:36.118503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.851373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:36.118515Z","caller":"traceutil/trace.go:171","msg":"trace[1693994056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"260.876953ms","start":"2026-02-10T21:30:35.857633Z","end":"2026-02-10T21:30:36.11851Z","steps":["trace[1693994056] 'agreement among raft nodes before linearized reading' (duration: 260.860373ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:44.49274Z","caller":"traceutil/trace.go:171","msg":"trace[1020538192] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"149.52159ms","start":"2026-02-10T21:30:44.343198Z","end":"2026-02-10T21:30:44.49272Z","steps":["trace[1020538192] 'read index received' (duration: 149.358506ms)","trace[1020538192] 'applied index is now lower than readState.Index' (duration: 161.884µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:44.492856Z","caller":"traceutil/trace.go:171","msg":"trace[69775916] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"234.576141ms","start":"2026-02-10T21:30:44.25827Z","end":"2026-02-10T21:30:44.492846Z","steps":["trace[69775916] 'process raft request' (duration: 234.318586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:44.49311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.897528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:44.493155Z","caller":"traceutil/trace.go:171","msg":"trace[588595499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"149.970949ms","start":"2026-02-10T21:30:44.343171Z","end":"2026-02-10T21:30:44.493142Z","steps":["trace[588595499] 'agreement among raft nodes before linearized reading' (duration: 149.900237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:48.797668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.391563ms","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-02-10T21:30:48.797714Z","caller":"traceutil/trace.go:171","msg":"trace[63776823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"232.472615ms","start":"2026-02-10T21:30:48.565232Z","end":"2026-02-10T21:30:48.797704Z","steps":["trace[63776823] 'range keys from in-memory index tree' (duration: 232.263581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:48.797834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.275357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T21:30:48.797848Z","caller":"traceutil/trace.go:171","msg":"trace[314534349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"226.288167ms","start":"2026-02-10T21:30:48.571554Z","end":"2026-02-10T21:30:48.797843Z","steps":["trace[314534349] 'range keys from in-memory index tree' (duration: 226.199256ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:48.906174Z","caller":"traceutil/trace.go:171","msg":"trace[1102849536] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"104.671736ms","start":"2026-02-10T21:30:48.801484Z","end":"2026-02-10T21:30:48.906156Z","steps":["trace[1102849536] 'process raft request' (duration: 104.547473ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:48.992913Z","caller":"traceutil/trace.go:171","msg":"trace[398370089] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"134.060727ms","start":"2026-02-10T21:30:48.858838Z","end":"2026-02-10T21:30:48.992898Z","steps":["trace[398370089] 'read index received' (duration: 47.177275ms)","trace[398370089] 'applied index is now lower than readState.Index' (duration: 86.882922ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:48.99302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.170669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:30:48.993047Z","caller":"traceutil/trace.go:171","msg":"trace[893435102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"134.23509ms","start":"2026-02-10T21:30:48.858805Z","end":"2026-02-10T21:30:48.99304Z","steps":["trace[893435102] 'agreement among raft nodes before linearized reading' (duration: 134.161339ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:48.99324Z","caller":"traceutil/trace.go:171","msg":"trace[1222234235] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"191.032118ms","start":"2026-02-10T21:30:48.802202Z","end":"2026-02-10T21:30:48.993234Z","steps":["trace[1222234235] 'process raft request' (duration: 190.619519ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:50.313522Z","caller":"traceutil/trace.go:171","msg":"trace[57054072] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"103.535921ms","start":"2026-02-10T21:30:50.209973Z","end":"2026-02-10T21:30:50.313509Z","steps":["trace[57054072] 'process raft request' (duration: 103.443809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:59.643683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.311141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T21:30:59.643769Z","caller":"traceutil/trace.go:171","msg":"trace[1815187495] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:988; }","duration":"330.444153ms","start":"2026-02-10T21:30:59.313305Z","end":"2026-02-10T21:30:59.643749Z","steps":["trace[1815187495] 'count revisions from in-memory index tree' (duration: 330.183298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:30:59.643815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:59.31329Z","time spent":"330.513365ms","remote":"127.0.0.1:45744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-02-10T21:30:59.683297Z","caller":"traceutil/trace.go:171","msg":"trace[99685484] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"120.705631ms","start":"2026-02-10T21:30:59.562574Z","end":"2026-02-10T21:30:59.683279Z","steps":["trace[99685484] 'process raft request' (duration: 120.595739ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:30:59.907417Z","caller":"traceutil/trace.go:171","msg":"trace[1365099716] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"256.575527ms","start":"2026-02-10T21:30:59.65082Z","end":"2026-02-10T21:30:59.907395Z","steps":["trace[1365099716] 'read index received' (duration: 32.846506ms)","trace[1365099716] 'applied index is now lower than readState.Index' (duration: 223.728101ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:30:59.907638Z","caller":"traceutil/trace.go:171","msg":"trace[1662780767] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"257.820715ms","start":"2026-02-10T21:30:59.649806Z","end":"2026-02-10T21:30:59.907626Z","steps":["trace[1662780767] 'process raft request' (duration: 228.557668ms)","trace[1662780767] 'compare' (duration: 28.646104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:30:59.907885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.048798ms","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-02-10T21:30:59.90792Z","caller":"traceutil/trace.go:171","msg":"trace[1088619665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"257.11117ms","start":"2026-02-10T21:30:59.650797Z","end":"2026-02-10T21:30:59.907908Z","steps":["trace[1088619665] 'agreement among raft nodes before linearized reading' (duration: 256.991267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:00.502944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835590766004783237,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:31:00.554556Z","caller":"traceutil/trace.go:171","msg":"trace[2072438410] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"551.877674ms","start":"2026-02-10T21:31:00.002657Z","end":"2026-02-10T21:31:00.554535Z","steps":["trace[2072438410] 'read index received' (duration: 549.356138ms)","trace[2072438410] 'applied index is now lower than readState.Index' (duration: 2.520516ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:31:00.554775Z","caller":"traceutil/trace.go:171","msg":"trace[1372770272] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"640.569927ms","start":"2026-02-10T21:30:59.914185Z","end":"2026-02-10T21:31:00.554755Z","steps":["trace[1372770272] 'process raft request' (duration: 637.788675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:00.555074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:30:59.914096Z","time spent":"640.897164ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:31:00.555616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.104977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-10T21:31:00.55595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.153243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T21:31:00.556048Z","caller":"traceutil/trace.go:171","msg":"trace[317153049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"117.242105ms","start":"2026-02-10T21:31:00.438765Z","end":"2026-02-10T21:31:00.556007Z","steps":["trace[317153049] 'agreement among raft nodes before linearized reading' (duration: 116.850306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:00.556349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.690214ms","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-02-10T21:31:00.5564Z","caller":"traceutil/trace.go:171","msg":"trace[993515076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"553.779576ms","start":"2026-02-10T21:31:00.002614Z","end":"2026-02-10T21:31:00.556393Z","steps":["trace[993515076] 'agreement among raft nodes before linearized reading' (duration: 553.688534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:00.556442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:00.002593Z","time spent":"553.842258ms","remote":"127.0.0.1:45538","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-02-10T21:31:00.558873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.557966ms","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:527"} {"level":"info","ts":"2026-02-10T21:31:00.559067Z","caller":"traceutil/trace.go:171","msg":"trace[1769130945] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:991; }","duration":"133.792981ms","start":"2026-02-10T21:31:00.425263Z","end":"2026-02-10T21:31:00.559056Z","steps":["trace[1769130945] 'agreement among raft nodes before linearized reading' (duration: 132.41143ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:31:00.561903Z","caller":"traceutil/trace.go:171","msg":"trace[1158791460] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:991; }","duration":"481.69282ms","start":"2026-02-10T21:31:00.073968Z","end":"2026-02-10T21:31:00.55566Z","steps":["trace[1158791460] 'agreement among raft nodes before linearized reading' (duration: 481.109337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:31:00.564536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:31:00.073955Z","time spent":"488.00578ms","remote":"127.0.0.1:45428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-10T21:31:44.362466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.083167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-10T21:31:44.362551Z","caller":"traceutil/trace.go:171","msg":"trace[256350595] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1171; }","duration":"132.24252ms","start":"2026-02-10T21:31:44.230289Z","end":"2026-02-10T21:31:44.362532Z","steps":["trace[256350595] 'range keys from in-memory index tree' (duration: 131.898653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:33:59.832936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.620175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-10T21:33:59.832993Z","caller":"traceutil/trace.go:171","msg":"trace[266148532] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1748; }","duration":"127.717907ms","start":"2026-02-10T21:33:59.705262Z","end":"2026-02-10T21:33:59.83298Z","steps":["trace[266148532] 'range keys from in-memory index tree' (duration: 127.491842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:34:07.964857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.455126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:34:07.964911Z","caller":"traceutil/trace.go:171","msg":"trace[1004836033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1786; }","duration":"103.548649ms","start":"2026-02-10T21:34:07.86135Z","end":"2026-02-10T21:34:07.964899Z","steps":["trace[1004836033] 'range keys from in-memory index tree' (duration: 103.361054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:34:07.965185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.7901ms","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-02-10T21:34:07.965209Z","caller":"traceutil/trace.go:171","msg":"trace[1943691422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1786; }","duration":"254.866961ms","start":"2026-02-10T21:34:07.710335Z","end":"2026-02-10T21:34:07.965202Z","steps":["trace[1943691422] 'range keys from in-memory index tree' (duration: 254.653417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:34:07.965209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.055523ms","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-02-10T21:34:07.965281Z","caller":"traceutil/trace.go:171","msg":"trace[589857600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1786; }","duration":"293.229517ms","start":"2026-02-10T21:34:07.672033Z","end":"2026-02-10T21:34:07.965262Z","steps":["trace[589857600] 'range keys from in-memory index tree' (duration: 292.90499ms)"],"step_count":1}