{"level":"warn","ts":"2026-02-12T03:42:13.854276Z","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-12T03:42:13.854406Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-12T03:42:13.854498Z","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-12T03:42:13.854513Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-12T03:42:13.854541Z","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-12T03:42:13.855272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-02-12T03:42:13.855379Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-12T03:42:13.861953Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.300135ms"} {"level":"info","ts":"2026-02-12T03:42:13.882081Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-02-12T03:42:13.88248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T03:42:13.882516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-02-12T03:42:13.882579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T03:42:13.882588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-02-12T03:42:13.883297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-02-12T03:42:13.893784Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T03:42:13.89563Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T03:42:13.898374Z","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-12T03:42:13.901257Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T03:42:13.902034Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T03:42:13.902201Z","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-12T03:42:13.90223Z","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-12T03:42:13.902261Z","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-12T03:42:13.905866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-02-12T03:42:13.905959Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-12T03:42:13.922074Z","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-12T03:42:13.922384Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T03:42:13.922416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T03:42:13.922589Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-12T03:42:13.922606Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-12T03:42:14.184185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T03:42:14.184545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T03:42:14.184583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-02-12T03:42:14.184903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-02-12T03:42:14.184932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-12T03:42:14.184947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-02-12T03:42:14.184957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-12T03:42:14.214115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:42:14.214081Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T03:42:14.215155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:42:14.215555Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T03:42:14.215578Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T03:42:14.215696Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:42:14.22914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T03:42:14.229288Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:42:14.229378Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:42:14.229409Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:42:14.257802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-02-12T03:42:43.977815Z","caller":"traceutil/trace.go:171","msg":"trace[2038520084] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"539.93105ms","start":"2026-02-12T03:42:43.437866Z","end":"2026-02-12T03:42:43.977797Z","steps":["trace[2038520084] 'process raft request' (duration: 539.810006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:42:43.978416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:42:43.437836Z","time spent":"540.083444ms","remote":"127.0.0.1:42806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:42:49.138668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.540026ms","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-12T03:42:49.138779Z","caller":"traceutil/trace.go:171","msg":"trace[307172464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"242.679609ms","start":"2026-02-12T03:42:48.896081Z","end":"2026-02-12T03:42:49.138761Z","steps":["trace[307172464] 'range keys from in-memory index tree' (duration: 242.412973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:42:49.139263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.160555ms","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-12T03:42:49.139305Z","caller":"traceutil/trace.go:171","msg":"trace[1326988030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"245.224727ms","start":"2026-02-12T03:42:48.894069Z","end":"2026-02-12T03:42:49.139294Z","steps":["trace[1326988030] 'range keys from in-memory index tree' (duration: 245.078773ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:42:49.287784Z","caller":"traceutil/trace.go:171","msg":"trace[1532756762] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"140.586445ms","start":"2026-02-12T03:42:49.147175Z","end":"2026-02-12T03:42:49.287761Z","steps":["trace[1532756762] 'process raft request' (duration: 140.445311ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:42:49.304146Z","caller":"traceutil/trace.go:171","msg":"trace[1948051538] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"156.926974ms","start":"2026-02-12T03:42:49.147195Z","end":"2026-02-12T03:42:49.304122Z","steps":["trace[1948051538] 'process raft request' (duration: 156.796039ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:42:50.358697Z","caller":"traceutil/trace.go:171","msg":"trace[951876838] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"191.577701ms","start":"2026-02-12T03:42:50.167103Z","end":"2026-02-12T03:42:50.358681Z","steps":["trace[951876838] 'read index received' (duration: 191.478129ms)","trace[951876838] 'applied index is now lower than readState.Index' (duration: 98.962µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:42:50.358776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.659723ms","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-12T03:42:50.358793Z","caller":"traceutil/trace.go:171","msg":"trace[1106831655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"191.708214ms","start":"2026-02-12T03:42:50.167079Z","end":"2026-02-12T03:42:50.358787Z","steps":["trace[1106831655] 'agreement among raft nodes before linearized reading' (duration: 191.663883ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:42:50.358913Z","caller":"traceutil/trace.go:171","msg":"trace[1237037883] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"199.246591ms","start":"2026-02-12T03:42:50.159642Z","end":"2026-02-12T03:42:50.358889Z","steps":["trace[1237037883] 'process raft request' (duration: 198.934934ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:42:51.022372Z","caller":"traceutil/trace.go:171","msg":"trace[388313082] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"158.924954ms","start":"2026-02-12T03:42:50.863427Z","end":"2026-02-12T03:42:51.022352Z","steps":["trace[388313082] 'process raft request' (duration: 158.74388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:42:53.609651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.890571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:42:53.610398Z","caller":"traceutil/trace.go:171","msg":"trace[363480903] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"305.421715ms","start":"2026-02-12T03:42:53.304954Z","end":"2026-02-12T03:42:53.610375Z","steps":["trace[363480903] 'process raft request' (duration: 52.590009ms)","trace[363480903] 'compare' (duration: 251.487011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:42:53.610496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:42:53.304937Z","time spent":"305.516808ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:42:53.611109Z","caller":"traceutil/trace.go:171","msg":"trace[1011704764] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"296.566202ms","start":"2026-02-12T03:42:53.314527Z","end":"2026-02-12T03:42:53.611093Z","steps":["trace[1011704764] 'read index received' (duration: 42.953915ms)","trace[1011704764] 'applied index is now lower than readState.Index' (duration: 253.610627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:42:53.675408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.861047ms","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-12T03:42:53.675479Z","caller":"traceutil/trace.go:171","msg":"trace[2112505397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"360.96846ms","start":"2026-02-12T03:42:53.314493Z","end":"2026-02-12T03:42:53.675461Z","steps":["trace[2112505397] 'agreement among raft nodes before linearized reading' (duration: 296.648115ms)","trace[2112505397] 'range keys from in-memory index tree' (duration: 64.206492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:42:53.675517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:42:53.314483Z","time spent":"361.025772ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:42:53.934399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.208389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:42:53.934476Z","caller":"traceutil/trace.go:171","msg":"trace[800864803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"102.336871ms","start":"2026-02-12T03:42:53.832122Z","end":"2026-02-12T03:42:53.934459Z","steps":["trace[800864803] 'range keys from in-memory index tree' (duration: 102.118296ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:42:57.210049Z","caller":"traceutil/trace.go:171","msg":"trace[1565946760] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"120.067736ms","start":"2026-02-12T03:42:57.089966Z","end":"2026-02-12T03:42:57.210034Z","steps":["trace[1565946760] 'read index received' (duration: 113.958635ms)","trace[1565946760] 'applied index is now lower than readState.Index' (duration: 6.108391ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:42:57.210446Z","caller":"traceutil/trace.go:171","msg":"trace[2063584758] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"285.625406ms","start":"2026-02-12T03:42:56.924806Z","end":"2026-02-12T03:42:57.210432Z","steps":["trace[2063584758] 'process raft request' (duration: 279.189587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:42:57.210641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.662052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-12T03:42:57.210903Z","caller":"traceutil/trace.go:171","msg":"trace[754529676] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"120.962359ms","start":"2026-02-12T03:42:57.089925Z","end":"2026-02-12T03:42:57.210888Z","steps":["trace[754529676] 'agreement among raft nodes before linearized reading' (duration: 120.625371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:05.956989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.46091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:43:05.95714Z","caller":"traceutil/trace.go:171","msg":"trace[563473816] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"748.146069ms","start":"2026-02-12T03:43:05.208984Z","end":"2026-02-12T03:43:05.95713Z","steps":["trace[563473816] 'read index received' (duration: 655.847µs)","trace[563473816] 'applied index is now lower than readState.Index' (duration: 747.489622ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:43:05.957266Z","caller":"traceutil/trace.go:171","msg":"trace[1469156684] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"757.465902ms","start":"2026-02-12T03:43:05.199795Z","end":"2026-02-12T03:43:05.957261Z","steps":["trace[1469156684] 'process raft request' (duration: 144.685933ms)","trace[1469156684] 'compare' (duration: 612.383858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:05.957298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.199778Z","time spent":"757.503203ms","remote":"127.0.0.1:42898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:05.957414Z","caller":"traceutil/trace.go:171","msg":"trace[1215457892] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"748.471358ms","start":"2026-02-12T03:43:05.208938Z","end":"2026-02-12T03:43:05.957409Z","steps":["trace[1215457892] 'process raft request' (duration: 748.136268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:05.957437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.208929Z","time spent":"748.494908ms","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1018,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:05.95749Z","caller":"traceutil/trace.go:171","msg":"trace[1710144960] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"748.469858ms","start":"2026-02-12T03:43:05.209017Z","end":"2026-02-12T03:43:05.957487Z","steps":["trace[1710144960] 'process raft request' (duration: 748.091097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:05.95751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.209014Z","time spent":"748.485838ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T03:43:05.957616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.631132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-82z9j\" ","response":"range_response_count:1 size:4812"} {"level":"info","ts":"2026-02-12T03:43:05.957629Z","caller":"traceutil/trace.go:171","msg":"trace[1202025572] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-82z9j; range_end:; response_count:1; response_revision:691; }","duration":"748.657502ms","start":"2026-02-12T03:43:05.208967Z","end":"2026-02-12T03:43:05.957624Z","steps":["trace[1202025572] 'agreement among raft nodes before linearized reading' (duration: 748.600501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:05.957642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.208963Z","time spent":"748.676062ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4836,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-82z9j\" "} {"level":"info","ts":"2026-02-12T03:43:06.001727Z","caller":"traceutil/trace.go:171","msg":"trace[2051097913] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"782.624437ms","start":"2026-02-12T03:43:05.219088Z","end":"2026-02-12T03:43:06.001712Z","steps":["trace[2051097913] 'process raft request' (duration: 782.595626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.001818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.219077Z","time spent":"782.694338ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:06.002035Z","caller":"traceutil/trace.go:171","msg":"trace[1245596766] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"792.727069ms","start":"2026-02-12T03:43:05.209303Z","end":"2026-02-12T03:43:06.00203Z","steps":["trace[1245596766] 'process raft request' (duration: 792.292018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.002063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.209298Z","time spent":"792.749249ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:43:06.002395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.149482ms","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-12T03:43:06.002414Z","caller":"traceutil/trace.go:171","msg":"trace[1810316348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"324.194022ms","start":"2026-02-12T03:43:05.678215Z","end":"2026-02-12T03:43:06.002409Z","steps":["trace[1810316348] 'agreement among raft nodes before linearized reading' (duration: 324.11539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.002428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.678202Z","time spent":"324.222724ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:43:06.002499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.571067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:43:06.002511Z","caller":"traceutil/trace.go:171","msg":"trace[1563980012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"170.620649ms","start":"2026-02-12T03:43:05.831886Z","end":"2026-02-12T03:43:06.002507Z","steps":["trace[1563980012] 'agreement among raft nodes before linearized reading' (duration: 170.602218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.00259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.513435ms","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-12T03:43:06.002603Z","caller":"traceutil/trace.go:171","msg":"trace[149776796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"245.545296ms","start":"2026-02-12T03:43:05.757054Z","end":"2026-02-12T03:43:06.0026Z","steps":["trace[149776796] 'agreement among raft nodes before linearized reading' (duration: 245.509255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.504279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477513273770182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T03:43:06.620693Z","caller":"traceutil/trace.go:171","msg":"trace[851974188] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"658.866827ms","start":"2026-02-12T03:43:05.961802Z","end":"2026-02-12T03:43:06.620669Z","steps":["trace[851974188] 'process raft request' (duration: 658.767954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.620818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:05.961787Z","time spent":"658.96985ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4809,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:06.981125Z","caller":"traceutil/trace.go:171","msg":"trace[33200257] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:715; }","duration":"977.121895ms","start":"2026-02-12T03:43:06.003984Z","end":"2026-02-12T03:43:06.981106Z","steps":["trace[33200257] 'read index received' (duration: 617.143762ms)","trace[33200257] 'applied index is now lower than readState.Index' (duration: 359.977123ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:43:06.98138Z","caller":"traceutil/trace.go:171","msg":"trace[133841123] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"972.099064ms","start":"2026-02-12T03:43:06.009254Z","end":"2026-02-12T03:43:06.981353Z","steps":["trace[133841123] 'process raft request' (duration: 918.230713ms)","trace[133841123] 'compare' (duration: 53.43106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:06.981473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.009239Z","time spent":"972.172556ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:06.981726Z","caller":"traceutil/trace.go:171","msg":"trace[560170987] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"972.335821ms","start":"2026-02-12T03:43:06.00938Z","end":"2026-02-12T03:43:06.981716Z","steps":["trace[560170987] 'process raft request' (duration: 971.631483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.981782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.009376Z","time spent":"972.375632ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:06.981898Z","caller":"traceutil/trace.go:171","msg":"trace[978406876] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"972.250598ms","start":"2026-02-12T03:43:06.009639Z","end":"2026-02-12T03:43:06.981889Z","steps":["trace[978406876] 'process raft request' (duration: 971.417967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.981945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.009633Z","time spent":"972.286109ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:43:06.982111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.120021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:43:06.982141Z","caller":"traceutil/trace.go:171","msg":"trace[971703867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"978.175372ms","start":"2026-02-12T03:43:06.003956Z","end":"2026-02-12T03:43:06.982131Z","steps":["trace[971703867] 'agreement among raft nodes before linearized reading' (duration: 978.127601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:06.982171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.003947Z","time spent":"978.218173ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:43:07.219505Z","caller":"traceutil/trace.go:171","msg":"trace[1628707755] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:718; }","duration":"238.302428ms","start":"2026-02-12T03:43:06.981181Z","end":"2026-02-12T03:43:07.219484Z","steps":["trace[1628707755] 'read index received' (duration: 238.028171ms)","trace[1628707755] 'applied index is now lower than readState.Index' (duration: 273.417µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:43:07.21973Z","caller":"traceutil/trace.go:171","msg":"trace[2026430457] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"594.177745ms","start":"2026-02-12T03:43:06.625542Z","end":"2026-02-12T03:43:07.219719Z","steps":["trace[2026430457] 'process raft request' (duration: 593.721202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.219841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.625528Z","time spent":"594.230136ms","remote":"127.0.0.1:42898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:07.220102Z","caller":"traceutil/trace.go:171","msg":"trace[1150999580] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"592.583903ms","start":"2026-02-12T03:43:06.627508Z","end":"2026-02-12T03:43:07.220092Z","steps":["trace[1150999580] 'process raft request' (duration: 591.863454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.220157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.627501Z","time spent":"592.626904ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:07.220295Z","caller":"traceutil/trace.go:171","msg":"trace[426286572] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"592.625525ms","start":"2026-02-12T03:43:06.627661Z","end":"2026-02-12T03:43:07.220286Z","steps":["trace[426286572] 'process raft request' (duration: 591.759811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.220339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.627656Z","time spent":"592.660395ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:43:07.220534Z","caller":"traceutil/trace.go:171","msg":"trace[1210371512] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"592.826879ms","start":"2026-02-12T03:43:06.627698Z","end":"2026-02-12T03:43:07.220524Z","steps":["trace[1210371512] 'process raft request' (duration: 591.752061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.220583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.627694Z","time spent":"592.86308ms","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:43:07.220949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.15996ms","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-12T03:43:07.220986Z","caller":"traceutil/trace.go:171","msg":"trace[1318827183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"555.227662ms","start":"2026-02-12T03:43:06.665748Z","end":"2026-02-12T03:43:07.220975Z","steps":["trace[1318827183] 'agreement among raft nodes before linearized reading' (duration: 555.107668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.221019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.665735Z","time spent":"555.273543ms","remote":"127.0.0.1:42892","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-12T03:43:07.221244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.274088ms","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-12T03:43:07.221324Z","caller":"traceutil/trace.go:171","msg":"trace[1051143909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"679.37313ms","start":"2026-02-12T03:43:06.541942Z","end":"2026-02-12T03:43:07.221315Z","steps":["trace[1051143909] 'agreement among raft nodes before linearized reading' (duration: 679.225006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.221353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.541929Z","time spent":"679.415502ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T03:43:07.22145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.519251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:43:07.221473Z","caller":"traceutil/trace.go:171","msg":"trace[1416283279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"750.567163ms","start":"2026-02-12T03:43:06.470899Z","end":"2026-02-12T03:43:07.221466Z","steps":["trace[1416283279] 'agreement among raft nodes before linearized reading' (duration: 750.505171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:07.221499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:06.470885Z","time spent":"750.608023ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T03:43:07.221575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.388062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:43:07.2216Z","caller":"traceutil/trace.go:171","msg":"trace[1529273887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"225.430884ms","start":"2026-02-12T03:43:06.99616Z","end":"2026-02-12T03:43:07.221591Z","steps":["trace[1529273887] 'agreement among raft nodes before linearized reading' (duration: 225.397183ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:13.119334Z","caller":"traceutil/trace.go:171","msg":"trace[142953449] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"105.499215ms","start":"2026-02-12T03:43:13.013815Z","end":"2026-02-12T03:43:13.119314Z","steps":["trace[142953449] 'read index received' (duration: 105.372361ms)","trace[142953449] 'applied index is now lower than readState.Index' (duration: 126.134µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:13.11949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.654659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T03:43:13.119516Z","caller":"traceutil/trace.go:171","msg":"trace[490669431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"105.71833ms","start":"2026-02-12T03:43:13.013792Z","end":"2026-02-12T03:43:13.11951Z","steps":["trace[490669431] 'agreement among raft nodes before linearized reading' (duration: 105.592997ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:13.119617Z","caller":"traceutil/trace.go:171","msg":"trace[2008468599] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"108.325128ms","start":"2026-02-12T03:43:13.011263Z","end":"2026-02-12T03:43:13.119588Z","steps":["trace[2008468599] 'process raft request' (duration: 107.919577ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:13.571625Z","caller":"traceutil/trace.go:171","msg":"trace[68585189] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"448.162087ms","start":"2026-02-12T03:43:13.123437Z","end":"2026-02-12T03:43:13.571599Z","steps":["trace[68585189] 'process raft request' (duration: 413.252919ms)","trace[68585189] 'compare' (duration: 34.005324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:13.571769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:13.12342Z","time spent":"448.275009ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:13.57438Z","caller":"traceutil/trace.go:171","msg":"trace[1334426492] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"305.787113ms","start":"2026-02-12T03:43:13.268559Z","end":"2026-02-12T03:43:13.574347Z","steps":["trace[1334426492] 'read index received' (duration: 268.160785ms)","trace[1334426492] 'applied index is now lower than readState.Index' (duration: 37.623908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:13.70543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.623961ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T03:43:13.705489Z","caller":"traceutil/trace.go:171","msg":"trace[1453887023] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"131.060879ms","start":"2026-02-12T03:43:13.574416Z","end":"2026-02-12T03:43:13.705477Z","steps":["trace[1453887023] 'read index received' (duration: 59.612µs)","trace[1453887023] 'applied index is now lower than readState.Index' (duration: 131.000357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:13.705593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.531846ms","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-12T03:43:13.705607Z","caller":"traceutil/trace.go:171","msg":"trace[1465669453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"423.570617ms","start":"2026-02-12T03:43:13.282032Z","end":"2026-02-12T03:43:13.705603Z","steps":["trace[1465669453] 'agreement among raft nodes before linearized reading' (duration: 423.472125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:13.705625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:13.282021Z","time spent":"423.600027ms","remote":"127.0.0.1:42892","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-12T03:43:13.705825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.267412ms","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-12T03:43:13.705839Z","caller":"traceutil/trace.go:171","msg":"trace[269563946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:723; }","duration":"437.315344ms","start":"2026-02-12T03:43:13.268519Z","end":"2026-02-12T03:43:13.705834Z","steps":["trace[269563946] 'agreement among raft nodes before linearized reading' (duration: 305.940967ms)","trace[269563946] 'range keys from in-memory index tree' (duration: 131.331335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:13.705851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:13.268501Z","time spent":"437.347915ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T03:43:13.705987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.582254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:43:13.706Z","caller":"traceutil/trace.go:171","msg":"trace[1455456559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"419.608465ms","start":"2026-02-12T03:43:13.286388Z","end":"2026-02-12T03:43:13.705996Z","steps":["trace[1455456559] 'agreement among raft nodes before linearized reading' (duration: 419.568054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:13.706011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:13.28638Z","time spent":"419.628245ms","remote":"127.0.0.1:42892","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-12T03:43:13.893253Z","caller":"traceutil/trace.go:171","msg":"trace[211924626] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"183.572094ms","start":"2026-02-12T03:43:13.709666Z","end":"2026-02-12T03:43:13.893238Z","steps":["trace[211924626] 'process raft request' (duration: 183.479212ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:13.899318Z","caller":"traceutil/trace.go:171","msg":"trace[1938413020] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"188.75887ms","start":"2026-02-12T03:43:13.710551Z","end":"2026-02-12T03:43:13.89931Z","steps":["trace[1938413020] 'process raft request' (duration: 188.740139ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:13.899449Z","caller":"traceutil/trace.go:171","msg":"trace[743154385] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"188.924804ms","start":"2026-02-12T03:43:13.71052Z","end":"2026-02-12T03:43:13.899444Z","steps":["trace[743154385] 'process raft request' (duration: 188.696558ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:28.683451Z","caller":"traceutil/trace.go:171","msg":"trace[1713468990] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"110.894904ms","start":"2026-02-12T03:43:28.572542Z","end":"2026-02-12T03:43:28.683437Z","steps":["trace[1713468990] 'process raft request' (duration: 110.74993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:32.121592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.203936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:43:32.121668Z","caller":"traceutil/trace.go:171","msg":"trace[1954601384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"150.293389ms","start":"2026-02-12T03:43:31.971357Z","end":"2026-02-12T03:43:32.12165Z","steps":["trace[1954601384] 'range keys from in-memory index tree' (duration: 150.094833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:32.12151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.353733ms","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-12T03:43:32.121857Z","caller":"traceutil/trace.go:171","msg":"trace[1384986590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"157.722982ms","start":"2026-02-12T03:43:31.964111Z","end":"2026-02-12T03:43:32.121834Z","steps":["trace[1384986590] 'range keys from in-memory index tree' (duration: 157.198899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:32.122153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.698633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:43:32.122187Z","caller":"traceutil/trace.go:171","msg":"trace[1335319167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"287.767275ms","start":"2026-02-12T03:43:31.834409Z","end":"2026-02-12T03:43:32.122177Z","steps":["trace[1335319167] 'range keys from in-memory index tree' (duration: 287.644032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:32.122477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.512414ms","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-12T03:43:32.122506Z","caller":"traceutil/trace.go:171","msg":"trace[416160982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"160.569186ms","start":"2026-02-12T03:43:31.961927Z","end":"2026-02-12T03:43:32.122496Z","steps":["trace[416160982] 'range keys from in-memory index tree' (duration: 160.413872ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:32.330187Z","caller":"traceutil/trace.go:171","msg":"trace[1567414282] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"201.688125ms","start":"2026-02-12T03:43:32.128478Z","end":"2026-02-12T03:43:32.330166Z","steps":["trace[1567414282] 'process raft request' (duration: 201.559312ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:32.353331Z","caller":"traceutil/trace.go:171","msg":"trace[726164943] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"222.559218ms","start":"2026-02-12T03:43:32.130755Z","end":"2026-02-12T03:43:32.353314Z","steps":["trace[726164943] 'process raft request' (duration: 222.523478ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:32.353852Z","caller":"traceutil/trace.go:171","msg":"trace[2003800995] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"223.305238ms","start":"2026-02-12T03:43:32.130531Z","end":"2026-02-12T03:43:32.353836Z","steps":["trace[2003800995] 'process raft request' (duration: 222.666562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:52.757282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.445116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:43:52.757416Z","caller":"traceutil/trace.go:171","msg":"trace[594988149] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"190.251359ms","start":"2026-02-12T03:43:52.567147Z","end":"2026-02-12T03:43:52.757398Z","steps":["trace[594988149] 'read index received' (duration: 87.591368ms)","trace[594988149] 'applied index is now lower than readState.Index' (duration: 102.656681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:52.75758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.464114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:43:52.757615Z","caller":"traceutil/trace.go:171","msg":"trace[280930080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"190.532246ms","start":"2026-02-12T03:43:52.567073Z","end":"2026-02-12T03:43:52.757605Z","steps":["trace[280930080] 'agreement among raft nodes before linearized reading' (duration: 190.382012ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:52.757885Z","caller":"traceutil/trace.go:171","msg":"trace[996840121] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"251.634495ms","start":"2026-02-12T03:43:52.506238Z","end":"2026-02-12T03:43:52.757873Z","steps":["trace[996840121] 'process raft request' (duration: 148.513402ms)","trace[996840121] 'compare' (duration: 102.27172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:59.349077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.337338ms","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-12T03:43:59.349125Z","caller":"traceutil/trace.go:171","msg":"trace[1950999609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"564.42817ms","start":"2026-02-12T03:43:58.784683Z","end":"2026-02-12T03:43:59.349112Z","steps":["trace[1950999609] 'range keys from in-memory index tree' (duration: 564.225746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:59.349146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:58.784669Z","time spent":"564.473762ms","remote":"127.0.0.1:42892","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-12T03:43:59.349178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.626567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:43:59.349249Z","caller":"traceutil/trace.go:171","msg":"trace[706236259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"561.74192ms","start":"2026-02-12T03:43:58.787487Z","end":"2026-02-12T03:43:59.349229Z","steps":["trace[706236259] 'range keys from in-memory index tree' (duration: 561.430543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:59.349296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:58.787466Z","time spent":"561.818543ms","remote":"127.0.0.1:42892","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-12T03:43:59.404414Z","caller":"traceutil/trace.go:171","msg":"trace[508041333] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"464.81574ms","start":"2026-02-12T03:43:58.939576Z","end":"2026-02-12T03:43:59.404391Z","steps":["trace[508041333] 'process raft request' (duration: 464.643376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:59.404875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:58.939552Z","time spent":"464.929372ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:43:59.404894Z","caller":"traceutil/trace.go:171","msg":"trace[1648952218] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:951; }","duration":"461.953896ms","start":"2026-02-12T03:43:58.942913Z","end":"2026-02-12T03:43:59.404867Z","steps":["trace[1648952218] 'read index received' (duration: 461.943265ms)","trace[1648952218] 'applied index is now lower than readState.Index' (duration: 8.891µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:59.405145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.203002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:43:59.405179Z","caller":"traceutil/trace.go:171","msg":"trace[709840077] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:917; }","duration":"462.288634ms","start":"2026-02-12T03:43:58.94288Z","end":"2026-02-12T03:43:59.405169Z","steps":["trace[709840077] 'agreement among raft nodes before linearized reading' (duration: 462.100319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:43:59.405216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:58.942868Z","time spent":"462.339155ms","remote":"127.0.0.1:43152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":116,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-12T03:43:59.552346Z","caller":"traceutil/trace.go:171","msg":"trace[1396174869] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:951; }","duration":"147.366664ms","start":"2026-02-12T03:43:59.404959Z","end":"2026-02-12T03:43:59.552325Z","steps":["trace[1396174869] 'read index received' (duration: 116.585043ms)","trace[1396174869] 'applied index is now lower than readState.Index' (duration: 30.78065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:43:59.552507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.267195ms","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-12T03:43:59.552499Z","caller":"traceutil/trace.go:171","msg":"trace[505147849] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"197.920338ms","start":"2026-02-12T03:43:59.354558Z","end":"2026-02-12T03:43:59.552479Z","steps":["trace[505147849] 'process raft request' (duration: 167.028675ms)","trace[505147849] 'compare' (duration: 30.590115ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:43:59.55254Z","caller":"traceutil/trace.go:171","msg":"trace[782542533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"161.342317ms","start":"2026-02-12T03:43:59.391189Z","end":"2026-02-12T03:43:59.552531Z","steps":["trace[782542533] 'agreement among raft nodes before linearized reading' (duration: 161.215063ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:59.552679Z","caller":"traceutil/trace.go:171","msg":"trace[116044648] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"196.610544ms","start":"2026-02-12T03:43:59.35606Z","end":"2026-02-12T03:43:59.552671Z","steps":["trace[116044648] 'process raft request' (duration: 196.219234ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:00.357352Z","caller":"traceutil/trace.go:171","msg":"trace[398426614] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"409.894671ms","start":"2026-02-12T03:43:59.947437Z","end":"2026-02-12T03:44:00.357331Z","steps":["trace[398426614] 'process raft request' (duration: 409.772748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:00.357477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:43:59.947427Z","time spent":"409.977064ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:44:00.762562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.425068ms","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-12T03:44:00.762625Z","caller":"traceutil/trace.go:171","msg":"trace[652606274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"289.5311ms","start":"2026-02-12T03:44:00.473076Z","end":"2026-02-12T03:44:00.762607Z","steps":["trace[652606274] 'range keys from in-memory index tree' (duration: 289.281834ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:00.987005Z","caller":"traceutil/trace.go:171","msg":"trace[1362430884] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"152.670751ms","start":"2026-02-12T03:44:00.834317Z","end":"2026-02-12T03:44:00.986988Z","steps":["trace[1362430884] 'read index received' (duration: 152.537348ms)","trace[1362430884] 'applied index is now lower than readState.Index' (duration: 132.843µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:44:00.987111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.782594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:44:00.987134Z","caller":"traceutil/trace.go:171","msg":"trace[638734519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"152.877155ms","start":"2026-02-12T03:44:00.834249Z","end":"2026-02-12T03:44:00.987126Z","steps":["trace[638734519] 'agreement among raft nodes before linearized reading' (duration: 152.808634ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:00.987881Z","caller":"traceutil/trace.go:171","msg":"trace[2131527767] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"217.983329ms","start":"2026-02-12T03:44:00.769876Z","end":"2026-02-12T03:44:00.98786Z","steps":["trace[2131527767] 'process raft request' (duration: 217.020745ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:03.698988Z","caller":"traceutil/trace.go:171","msg":"trace[1644248697] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"135.7201ms","start":"2026-02-12T03:44:03.563244Z","end":"2026-02-12T03:44:03.698964Z","steps":["trace[1644248697] 'read index received' (duration: 41.430718ms)","trace[1644248697] 'applied index is now lower than readState.Index' (duration: 94.288012ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:44:03.699635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.90175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:44:03.699684Z","caller":"traceutil/trace.go:171","msg":"trace[1808636737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"131.970853ms","start":"2026-02-12T03:44:03.567701Z","end":"2026-02-12T03:44:03.699672Z","steps":["trace[1808636737] 'agreement among raft nodes before linearized reading' (duration: 131.865599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:03.699928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.401294ms","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-12T03:44:03.699957Z","caller":"traceutil/trace.go:171","msg":"trace[1870349371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"127.438614ms","start":"2026-02-12T03:44:03.572509Z","end":"2026-02-12T03:44:03.699947Z","steps":["trace[1870349371] 'agreement among raft nodes before linearized reading' (duration: 127.357472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:03.700298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.13999ms","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-12T03:44:03.70034Z","caller":"traceutil/trace.go:171","msg":"trace[479850933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"137.109907ms","start":"2026-02-12T03:44:03.56322Z","end":"2026-02-12T03:44:03.70033Z","steps":["trace[479850933] 'agreement among raft nodes before linearized reading' (duration: 136.020578ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:08.664789Z","caller":"traceutil/trace.go:171","msg":"trace[762885724] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"250.932431ms","start":"2026-02-12T03:44:08.413841Z","end":"2026-02-12T03:44:08.664773Z","steps":["trace[762885724] 'read index received' (duration: 250.778526ms)","trace[762885724] 'applied index is now lower than readState.Index' (duration: 153.255µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:44:08.664809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:44:08.122527Z","time spent":"542.276919ms","remote":"127.0.0.1:42726","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T03:44:08.664921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.063114ms","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-12T03:44:08.66494Z","caller":"traceutil/trace.go:171","msg":"trace[312305987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"251.120026ms","start":"2026-02-12T03:44:08.413815Z","end":"2026-02-12T03:44:08.664935Z","steps":["trace[312305987] 'agreement among raft nodes before linearized reading' (duration: 251.015603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:12.124106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.0221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:44:12.124264Z","caller":"traceutil/trace.go:171","msg":"trace[123223177] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:987; }","duration":"294.217173ms","start":"2026-02-12T03:44:11.830031Z","end":"2026-02-12T03:44:12.124248Z","steps":["trace[123223177] 'read index received' (duration: 19.984378ms)","trace[123223177] 'applied index is now lower than readState.Index' (duration: 274.231955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:44:12.124335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.304285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:44:12.12436Z","caller":"traceutil/trace.go:171","msg":"trace[1103950349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"294.355207ms","start":"2026-02-12T03:44:11.829997Z","end":"2026-02-12T03:44:12.124352Z","steps":["trace[1103950349] 'agreement among raft nodes before linearized reading' (duration: 294.296735ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:12.124646Z","caller":"traceutil/trace.go:171","msg":"trace[302765017] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"325.920037ms","start":"2026-02-12T03:44:11.798711Z","end":"2026-02-12T03:44:12.124631Z","steps":["trace[302765017] 'process raft request' (duration: 51.295931ms)","trace[302765017] 'compare' (duration: 273.831094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:44:12.124746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:44:11.79869Z","time spent":"325.982728ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:44:12.124878Z","caller":"traceutil/trace.go:171","msg":"trace[193824903] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"323.446891ms","start":"2026-02-12T03:44:11.801421Z","end":"2026-02-12T03:44:12.124868Z","steps":["trace[193824903] 'process raft request' (duration: 322.773444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:12.124928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:44:11.801403Z","time spent":"323.497393ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:44:12.129178Z","caller":"traceutil/trace.go:171","msg":"trace[894043507] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"199.312131ms","start":"2026-02-12T03:44:11.929848Z","end":"2026-02-12T03:44:12.12916Z","steps":["trace[894043507] 'process raft request' (duration: 199.236509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:12.532746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.326308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:44:12.532804Z","caller":"traceutil/trace.go:171","msg":"trace[321716106] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:954; }","duration":"301.424971ms","start":"2026-02-12T03:44:12.231364Z","end":"2026-02-12T03:44:12.532789Z","steps":["trace[321716106] 'count revisions from in-memory index tree' (duration: 301.264466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:12.532831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:44:12.23135Z","time spent":"301.474092ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:44:14.072315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.749751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:44:14.072371Z","caller":"traceutil/trace.go:171","msg":"trace[275915809] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:957; }","duration":"113.838953ms","start":"2026-02-12T03:44:13.958519Z","end":"2026-02-12T03:44:14.072358Z","steps":["trace[275915809] 'count revisions from in-memory index tree' (duration: 113.641879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:44:14.95962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.697467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:44:14.959674Z","caller":"traceutil/trace.go:171","msg":"trace[618418073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"126.758508ms","start":"2026-02-12T03:44:14.832903Z","end":"2026-02-12T03:44:14.959661Z","steps":["trace[618418073] 'range keys from in-memory index tree' (duration: 126.595085ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:16.336387Z","caller":"traceutil/trace.go:171","msg":"trace[155007373] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"162.168348ms","start":"2026-02-12T03:44:16.174176Z","end":"2026-02-12T03:44:16.336345Z","steps":["trace[155007373] 'process raft request' (duration: 161.928701ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:16.347193Z","caller":"traceutil/trace.go:171","msg":"trace[590742415] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"133.897464ms","start":"2026-02-12T03:44:16.213283Z","end":"2026-02-12T03:44:16.347181Z","steps":["trace[590742415] 'read index received' (duration: 123.317979ms)","trace[590742415] 'applied index is now lower than readState.Index' (duration: 10.578805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:44:16.347326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.044478ms","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-12T03:44:16.347358Z","caller":"traceutil/trace.go:171","msg":"trace[1074032627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"134.118449ms","start":"2026-02-12T03:44:16.213232Z","end":"2026-02-12T03:44:16.347351Z","steps":["trace[1074032627] 'agreement among raft nodes before linearized reading' (duration: 134.001886ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:44:16.347611Z","caller":"traceutil/trace.go:171","msg":"trace[779189990] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"170.918136ms","start":"2026-02-12T03:44:16.176685Z","end":"2026-02-12T03:44:16.347603Z","steps":["trace[779189990] 'process raft request' (duration: 170.422553ms)"],"step_count":1}