{"level":"warn","ts":"2026-02-22T21:40:36.246912Z","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-22T21:40:36.24702Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-22T21:40:36.247123Z","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-22T21:40:36.247142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-22T21:40:36.24717Z","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-22T21:40:36.248064Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-02-22T21:40:36.248173Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-22T21:40:36.252263Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.84044ms"} {"level":"info","ts":"2026-02-22T21:40:36.261665Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-02-22T21:40:36.261758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T21:40:36.261782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-02-22T21:40:36.261795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T21:40:36.261804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-02-22T21:40:36.261839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-02-22T21:40:36.269845Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T21:40:36.270944Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T21:40:36.272857Z","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-22T21:40:36.274631Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T21:40:36.276098Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T21:40:36.276158Z","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-22T21:40:36.276282Z","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-22T21:40:36.276301Z","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-22T21:40:36.277253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-02-22T21:40:36.277408Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-02-22T21:40:36.27884Z","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-22T21:40:36.278967Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-22T21:40:36.279008Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-02-22T21:40:36.279068Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T21:40:36.279108Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T21:40:36.563372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T21:40:36.563423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T21:40:36.563452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-02-22T21:40:36.563464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-02-22T21:40:36.56347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-22T21:40:36.563478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-02-22T21:40:36.563486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-02-22T21:40:36.575515Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T21:40:36.583133Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T21:40:36.583331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T21:40:36.583377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T21:40:36.583869Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T21:40:36.583931Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-22T21:40:36.586938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-02-22T21:40:36.58903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T21:40:36.590041Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T21:40:36.600845Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T21:40:36.600903Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T21:40:43.006874Z","caller":"traceutil/trace.go:171","msg":"trace[789671395] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"409.419517ms","start":"2026-02-22T21:40:42.597428Z","end":"2026-02-22T21:40:43.006848Z","steps":["trace[789671395] 'process raft request' (duration: 378.497078ms)","trace[789671395] 'compare' (duration: 30.805006ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:40:43.006956Z","caller":"traceutil/trace.go:171","msg":"trace[1347778531] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"409.246374ms","start":"2026-02-22T21:40:42.597671Z","end":"2026-02-22T21:40:43.006917Z","steps":["trace[1347778531] 'process raft request' (duration: 409.123421ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:40:43.007013Z","caller":"traceutil/trace.go:171","msg":"trace[1598284422] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:281; }","duration":"395.301624ms","start":"2026-02-22T21:40:42.611677Z","end":"2026-02-22T21:40:43.006979Z","steps":["trace[1598284422] 'read index received' (duration: 364.320333ms)","trace[1598284422] 'applied index is now lower than readState.Index' (duration: 30.980281ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:40:43.007117Z","caller":"traceutil/trace.go:171","msg":"trace[2135157599] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"397.806333ms","start":"2026-02-22T21:40:42.609299Z","end":"2026-02-22T21:40:43.007105Z","steps":["trace[2135157599] 'process raft request' (duration: 397.555837ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:40:43.007124Z","caller":"traceutil/trace.go:171","msg":"trace[455152023] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"409.07652ms","start":"2026-02-22T21:40:42.598019Z","end":"2026-02-22T21:40:43.007096Z","steps":["trace[455152023] 'process raft request' (duration: 408.813264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:40:43.007224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.491258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"warn","ts":"2026-02-22T21:40:43.007278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.180511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-22T21:40:43.007324Z","caller":"traceutil/trace.go:171","msg":"trace[475341517] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:269; }","duration":"395.634512ms","start":"2026-02-22T21:40:42.611647Z","end":"2026-02-22T21:40:43.007281Z","steps":["trace[475341517] 'agreement among raft nodes before linearized reading' (duration: 395.477158ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:40:43.00734Z","caller":"traceutil/trace.go:171","msg":"trace[894364550] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:269; }","duration":"378.285993ms","start":"2026-02-22T21:40:42.629043Z","end":"2026-02-22T21:40:43.007329Z","steps":["trace[894364550] 'agreement among raft nodes before linearized reading' (duration: 378.13716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:40:43.007374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:42.629028Z","time spent":"378.338624ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-22T21:40:43.007369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:42.611635Z","time spent":"395.722884ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5892,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-22T21:40:43.007395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:42.597399Z","time spent":"409.587871ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T21:40:43.007431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:42.598002Z","time spent":"409.205142ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T21:40:43.00753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:42.609261Z","time spent":"397.879885ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6879,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:40:43.007511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:42.597659Z","time spent":"409.379286ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T21:40:43.584826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349723723671442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T21:40:43.821305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.622257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:40:43.821379Z","caller":"traceutil/trace.go:171","msg":"trace[828604183] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"738.393918ms","start":"2026-02-22T21:40:43.082974Z","end":"2026-02-22T21:40:43.821368Z","steps":["trace[828604183] 'process raft request' (duration: 508.551576ms)","trace[828604183] 'compare' (duration: 229.516154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:40:43.821416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:43.082954Z","time spent":"738.44794ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:40:44.442429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.677084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:40:44.442515Z","caller":"traceutil/trace.go:171","msg":"trace[64028192] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:286; }","duration":"1.358783103s","start":"2026-02-22T21:40:43.083717Z","end":"2026-02-22T21:40:44.4425Z","steps":["trace[64028192] 'read index received' (duration: 507.82442ms)","trace[64028192] 'applied index is now lower than readState.Index' (duration: 850.957473ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:40:44.442663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358950846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-22T21:40:44.442703Z","caller":"traceutil/trace.go:171","msg":"trace[483919263] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:272; }","duration":"1.359025488s","start":"2026-02-22T21:40:43.083666Z","end":"2026-02-22T21:40:44.442692Z","steps":["trace[483919263] 'agreement among raft nodes before linearized reading' (duration: 1.358958737s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:40:44.442737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:43.083648Z","time spent":"1.35908269s","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-22T21:40:44.442821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.11669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-22T21:40:44.442974Z","caller":"traceutil/trace.go:171","msg":"trace[1660745444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:272; }","duration":"782.307164ms","start":"2026-02-22T21:40:43.660653Z","end":"2026-02-22T21:40:44.44296Z","steps":["trace[1660745444] 'agreement among raft nodes before linearized reading' (duration: 782.086489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:40:44.443018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:43.660634Z","time spent":"782.373865ms","remote":"127.0.0.1:52188","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-22T21:40:44.443219Z","caller":"traceutil/trace.go:171","msg":"trace[283266954] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"1.356448838s","start":"2026-02-22T21:40:43.086137Z","end":"2026-02-22T21:40:44.442586Z","steps":["trace[283266954] 'process raft request' (duration: 1.163273881s)","trace[283266954] 'compare' (duration: 192.528601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:40:44.443329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:43.086122Z","time spent":"1.357143903s","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:40:44.443967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357664576s","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-22T21:40:44.444099Z","caller":"traceutil/trace.go:171","msg":"trace[272678048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:272; }","duration":"1.357832249s","start":"2026-02-22T21:40:43.086239Z","end":"2026-02-22T21:40:44.444072Z","steps":["trace[272678048] 'agreement among raft nodes before linearized reading' (duration: 1.356426397s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:40:44.444166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:40:43.08623Z","time spent":"1.357921232s","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T21:41:02.643803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.770518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-22T21:41:02.643902Z","caller":"traceutil/trace.go:171","msg":"trace[627903613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"100.90382ms","start":"2026-02-22T21:41:02.542977Z","end":"2026-02-22T21:41:02.643881Z","steps":["trace[627903613] 'range keys from in-memory index tree' (duration: 100.678995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:02.643914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.952871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-22T21:41:02.643852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.733367ms","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-22T21:41:02.644011Z","caller":"traceutil/trace.go:171","msg":"trace[1891513975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"101.083565ms","start":"2026-02-22T21:41:02.542909Z","end":"2026-02-22T21:41:02.643992Z","steps":["trace[1891513975] 'range keys from in-memory index tree' (duration: 100.8416ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:02.644038Z","caller":"traceutil/trace.go:171","msg":"trace[839825352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"117.923382ms","start":"2026-02-22T21:41:02.526084Z","end":"2026-02-22T21:41:02.644008Z","steps":["trace[839825352] 'range keys from in-memory index tree' (duration: 117.675736ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:06.070998Z","caller":"traceutil/trace.go:171","msg":"trace[1464389916] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"137.292628ms","start":"2026-02-22T21:41:05.93368Z","end":"2026-02-22T21:41:06.070972Z","steps":["trace[1464389916] 'process raft request' (duration: 137.133514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:11.764984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.431419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7d4mn\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-22T21:41:11.765068Z","caller":"traceutil/trace.go:171","msg":"trace[1822120648] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7d4mn; range_end:; response_count:1; response_revision:482; }","duration":"100.558472ms","start":"2026-02-22T21:41:11.664492Z","end":"2026-02-22T21:41:11.76505Z","steps":["trace[1822120648] 'range keys from in-memory index tree' (duration: 100.246105ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:12.548625Z","caller":"traceutil/trace.go:171","msg":"trace[241822787] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"457.603714ms","start":"2026-02-22T21:41:12.090993Z","end":"2026-02-22T21:41:12.548597Z","steps":["trace[241822787] 'read index received' (duration: 457.563723ms)","trace[241822787] 'applied index is now lower than readState.Index' (duration: 38.761µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:41:12.548746Z","caller":"traceutil/trace.go:171","msg":"trace[123343126] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"749.256684ms","start":"2026-02-22T21:41:11.799478Z","end":"2026-02-22T21:41:12.548735Z","steps":["trace[123343126] 'process raft request' (duration: 748.912075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:12.548894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:11.799448Z","time spent":"749.331876ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:41:12.548972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.951123ms","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-22T21:41:12.549078Z","caller":"traceutil/trace.go:171","msg":"trace[1036880347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"458.089916ms","start":"2026-02-22T21:41:12.090951Z","end":"2026-02-22T21:41:12.54904Z","steps":["trace[1036880347] 'agreement among raft nodes before linearized reading' (duration: 457.920982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:12.549122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.090927Z","time spent":"458.186448ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T21:41:13.020743Z","caller":"traceutil/trace.go:171","msg":"trace[542118648] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"928.073722ms","start":"2026-02-22T21:41:12.092641Z","end":"2026-02-22T21:41:13.020714Z","steps":["trace[542118648] 'process raft request' (duration: 927.911248ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:13.020868Z","caller":"traceutil/trace.go:171","msg":"trace[1303183872] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"472.143447ms","start":"2026-02-22T21:41:12.548699Z","end":"2026-02-22T21:41:13.020842Z","steps":["trace[1303183872] 'read index received' (duration: 471.789439ms)","trace[1303183872] 'applied index is now lower than readState.Index' (duration: 352.818µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:41:13.020947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.092612Z","time spent":"928.244465ms","remote":"127.0.0.1:52088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4346,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:41:13.021048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.59267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:41:13.02102Z","caller":"traceutil/trace.go:171","msg":"trace[165344598] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"681.29216ms","start":"2026-02-22T21:41:12.339656Z","end":"2026-02-22T21:41:13.020948Z","steps":["trace[165344598] 'process raft request' (duration: 681.022154ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:13.021138Z","caller":"traceutil/trace.go:171","msg":"trace[496343523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"871.734333ms","start":"2026-02-22T21:41:12.149383Z","end":"2026-02-22T21:41:13.021117Z","steps":["trace[496343523] 'agreement among raft nodes before linearized reading' (duration: 871.561719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:13.021196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.149363Z","time spent":"871.814285ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T21:41:13.021284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.339631Z","time spent":"681.524516ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:41:13.068364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.967982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-22T21:41:13.068425Z","caller":"traceutil/trace.go:171","msg":"trace[1919894220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"310.065884ms","start":"2026-02-22T21:41:12.758344Z","end":"2026-02-22T21:41:13.06841Z","steps":["trace[1919894220] 'agreement among raft nodes before linearized reading' (duration: 309.969032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:13.068461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.758322Z","time spent":"310.131356ms","remote":"127.0.0.1:52188","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":"warn","ts":"2026-02-22T21:41:13.068446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.577282ms","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-22T21:41:13.068532Z","caller":"traceutil/trace.go:171","msg":"trace[1969594792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"309.697614ms","start":"2026-02-22T21:41:12.758817Z","end":"2026-02-22T21:41:13.068515Z","steps":["trace[1969594792] 'agreement among raft nodes before linearized reading' (duration: 309.461999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:13.068568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.758813Z","time spent":"309.746895ms","remote":"127.0.0.1:52188","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-22T21:41:13.068634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.373592ms","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-22T21:41:13.068676Z","caller":"traceutil/trace.go:171","msg":"trace[654214786] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"514.23116ms","start":"2026-02-22T21:41:12.554435Z","end":"2026-02-22T21:41:13.068666Z","steps":["trace[654214786] 'process raft request' (duration: 513.671747ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:13.068686Z","caller":"traceutil/trace.go:171","msg":"trace[367856656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"310.458903ms","start":"2026-02-22T21:41:12.758216Z","end":"2026-02-22T21:41:13.068675Z","steps":["trace[367856656] 'agreement among raft nodes before linearized reading' (duration: 310.368071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:13.068722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.758199Z","time spent":"310.506585ms","remote":"127.0.0.1:52188","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-22T21:41:13.068753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:12.55442Z","time spent":"514.277521ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:41:13.286292Z","caller":"traceutil/trace.go:171","msg":"trace[1183787593] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"213.285711ms","start":"2026-02-22T21:41:13.072941Z","end":"2026-02-22T21:41:13.286227Z","steps":["trace[1183787593] 'process raft request' (duration: 190.974285ms)","trace[1183787593] 'compare' (duration: 21.964278ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:41:13.286838Z","caller":"traceutil/trace.go:171","msg":"trace[1754332271] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"213.707221ms","start":"2026-02-22T21:41:13.073115Z","end":"2026-02-22T21:41:13.286823Z","steps":["trace[1754332271] 'process raft request' (duration: 213.017505ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:17.058133Z","caller":"traceutil/trace.go:171","msg":"trace[747686460] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"134.38613ms","start":"2026-02-22T21:41:16.923715Z","end":"2026-02-22T21:41:17.058101Z","steps":["trace[747686460] 'process raft request' (duration: 134.233966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:27.456515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.764399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-22T21:41:27.456532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.712988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-22T21:41:27.456578Z","caller":"traceutil/trace.go:171","msg":"trace[722885525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"109.853791ms","start":"2026-02-22T21:41:27.34671Z","end":"2026-02-22T21:41:27.456564Z","steps":["trace[722885525] 'range keys from in-memory index tree' (duration: 109.670387ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:27.456612Z","caller":"traceutil/trace.go:171","msg":"trace[195814350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"101.820132ms","start":"2026-02-22T21:41:27.35477Z","end":"2026-02-22T21:41:27.45659Z","steps":["trace[195814350] 'range keys from in-memory index tree' (duration: 101.573686ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:33.399654Z","caller":"traceutil/trace.go:171","msg":"trace[2059526540] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"128.43672ms","start":"2026-02-22T21:41:33.27119Z","end":"2026-02-22T21:41:33.399626Z","steps":["trace[2059526540] 'process raft request' (duration: 128.299267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:33.986413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349723723672240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T21:41:34.816378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.341184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:41:34.816489Z","caller":"traceutil/trace.go:171","msg":"trace[327874929] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"1.330192672s","start":"2026-02-22T21:41:33.48628Z","end":"2026-02-22T21:41:34.816472Z","steps":["trace[327874929] 'read index received' (duration: 675.646094ms)","trace[327874929] 'applied index is now lower than readState.Index' (duration: 654.545118ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:41:34.816606Z","caller":"traceutil/trace.go:171","msg":"trace[976297975] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.54300732s","start":"2026-02-22T21:41:33.27358Z","end":"2026-02-22T21:41:34.816587Z","steps":["trace[976297975] 'process raft request' (duration: 888.353109ms)","trace[976297975] 'compare' (duration: 653.940875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:41:34.816683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:33.273554Z","time spent":"1.543092921s","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:41:34.816951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327216846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-22T21:41:34.817006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.148996ms","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-22T21:41:34.817031Z","caller":"traceutil/trace.go:171","msg":"trace[789408184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"1.327316948s","start":"2026-02-22T21:41:33.489693Z","end":"2026-02-22T21:41:34.817009Z","steps":["trace[789408184] 'agreement among raft nodes before linearized reading' (duration: 1.327077562s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:34.81708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.082967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T21:41:34.817086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:33.489685Z","time spent":"1.32739286s","remote":"127.0.0.1:52188","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-22T21:41:34.817104Z","caller":"traceutil/trace.go:171","msg":"trace[1848748182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"382.285699ms","start":"2026-02-22T21:41:34.434784Z","end":"2026-02-22T21:41:34.81707Z","steps":["trace[1848748182] 'agreement among raft nodes before linearized reading' (duration: 382.025843ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:34.817117Z","caller":"traceutil/trace.go:171","msg":"trace[2002260359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"670.21163ms","start":"2026-02-22T21:41:34.146893Z","end":"2026-02-22T21:41:34.817104Z","steps":["trace[2002260359] 'agreement among raft nodes before linearized reading' (duration: 670.153828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:34.817152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:34.146872Z","time spent":"670.275022ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T21:41:34.81716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:34.434767Z","time spent":"382.384151ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T21:41:34.817159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33086761s","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-22T21:41:34.817236Z","caller":"traceutil/trace.go:171","msg":"trace[1687479309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"1.330960891s","start":"2026-02-22T21:41:33.48625Z","end":"2026-02-22T21:41:34.817211Z","steps":["trace[1687479309] 'agreement among raft nodes before linearized reading' (duration: 1.330786987s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:34.817271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:33.486237Z","time spent":"1.331026442s","remote":"127.0.0.1:52188","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":"info","ts":"2026-02-22T21:41:34.982535Z","caller":"traceutil/trace.go:171","msg":"trace[309795397] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"161.166851ms","start":"2026-02-22T21:41:34.821349Z","end":"2026-02-22T21:41:34.982515Z","steps":["trace[309795397] 'process raft request' (duration: 107.649492ms)","trace[309795397] 'compare' (duration: 53.266273ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:41:34.982514Z","caller":"traceutil/trace.go:171","msg":"trace[487791301] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"159.790379ms","start":"2026-02-22T21:41:34.822697Z","end":"2026-02-22T21:41:34.982487Z","steps":["trace[487791301] 'process raft request' (duration: 159.696307ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:35.647598Z","caller":"traceutil/trace.go:171","msg":"trace[1224685577] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"243.897741ms","start":"2026-02-22T21:41:35.403681Z","end":"2026-02-22T21:41:35.647579Z","steps":["trace[1224685577] 'read index received' (duration: 243.815569ms)","trace[1224685577] 'applied index is now lower than readState.Index' (duration: 81.312µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:41:35.647759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.059274ms","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-22T21:41:35.64778Z","caller":"traceutil/trace.go:171","msg":"trace[2015734109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"244.123455ms","start":"2026-02-22T21:41:35.40365Z","end":"2026-02-22T21:41:35.647773Z","steps":["trace[2015734109] 'agreement among raft nodes before linearized reading' (duration: 244.002802ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:35.647892Z","caller":"traceutil/trace.go:171","msg":"trace[1086002756] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"474.156798ms","start":"2026-02-22T21:41:35.173729Z","end":"2026-02-22T21:41:35.647885Z","steps":["trace[1086002756] 'process raft request' (duration: 473.712407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:41:35.64796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:41:35.173711Z","time spent":"474.194298ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:41:35.76183Z","caller":"traceutil/trace.go:171","msg":"trace[1021178381] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"110.377585ms","start":"2026-02-22T21:41:35.651429Z","end":"2026-02-22T21:41:35.761807Z","steps":["trace[1021178381] 'process raft request' (duration: 110.255512ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:56.024512Z","caller":"traceutil/trace.go:171","msg":"trace[507371290] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"161.539849ms","start":"2026-02-22T21:41:55.862949Z","end":"2026-02-22T21:41:56.024489Z","steps":["trace[507371290] 'read index received' (duration: 161.345515ms)","trace[507371290] 'applied index is now lower than readState.Index' (duration: 193.454µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:41:56.024712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.750614ms","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-22T21:41:56.024752Z","caller":"traceutil/trace.go:171","msg":"trace[295374608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"161.850167ms","start":"2026-02-22T21:41:55.862893Z","end":"2026-02-22T21:41:56.024743Z","steps":["trace[295374608] 'agreement among raft nodes before linearized reading' (duration: 161.696443ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:56.024707Z","caller":"traceutil/trace.go:171","msg":"trace[131800242] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"280.01623ms","start":"2026-02-22T21:41:55.744664Z","end":"2026-02-22T21:41:56.02468Z","steps":["trace[131800242] 'process raft request' (duration: 279.687913ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:56.300812Z","caller":"traceutil/trace.go:171","msg":"trace[423970267] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"254.102685ms","start":"2026-02-22T21:41:56.046675Z","end":"2026-02-22T21:41:56.300778Z","steps":["trace[423970267] 'read index received' (duration: 226.117452ms)","trace[423970267] 'applied index is now lower than readState.Index' (duration: 27.935321ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:41:56.300875Z","caller":"traceutil/trace.go:171","msg":"trace[2040112828] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"271.426333ms","start":"2026-02-22T21:41:56.029421Z","end":"2026-02-22T21:41:56.300848Z","steps":["trace[2040112828] 'process raft request' (duration: 243.41394ms)","trace[2040112828] 'compare' (duration: 27.777518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:41:56.300962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.250408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-22T21:41:56.301037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.538034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:41:56.301148Z","caller":"traceutil/trace.go:171","msg":"trace[1339396934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"152.710018ms","start":"2026-02-22T21:41:56.148423Z","end":"2026-02-22T21:41:56.301133Z","steps":["trace[1339396934] 'agreement among raft nodes before linearized reading' (duration: 152.517273ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:41:56.301084Z","caller":"traceutil/trace.go:171","msg":"trace[567923782] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:737; }","duration":"254.417512ms","start":"2026-02-22T21:41:56.046644Z","end":"2026-02-22T21:41:56.301062Z","steps":["trace[567923782] 'agreement among raft nodes before linearized reading' (duration: 254.089305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:10.649441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349723723672640,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T21:42:10.872675Z","caller":"traceutil/trace.go:171","msg":"trace[1331590912] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"727.837173ms","start":"2026-02-22T21:42:10.144815Z","end":"2026-02-22T21:42:10.872652Z","steps":["trace[1331590912] 'process raft request' (duration: 727.69335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:10.872819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:10.144792Z","time spent":"727.952805ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:42:11.480544Z","caller":"traceutil/trace.go:171","msg":"trace[171675920] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:865; }","duration":"1.331693089s","start":"2026-02-22T21:42:10.148824Z","end":"2026-02-22T21:42:11.480517Z","steps":["trace[171675920] 'read index received' (duration: 723.700917ms)","trace[171675920] 'applied index is now lower than readState.Index' (duration: 607.991042ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:42:11.480677Z","caller":"traceutil/trace.go:171","msg":"trace[1650545783] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"947.098939ms","start":"2026-02-22T21:42:10.533523Z","end":"2026-02-22T21:42:11.480622Z","steps":["trace[1650545783] 'process raft request' (duration: 946.835623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.480724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331884303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:42:11.480789Z","caller":"traceutil/trace.go:171","msg":"trace[2067088921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"1.331991925s","start":"2026-02-22T21:42:10.14878Z","end":"2026-02-22T21:42:11.480772Z","steps":["trace[2067088921] 'agreement among raft nodes before linearized reading' (duration: 1.331855022s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.480785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102957526s","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-22T21:42:11.480816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:10.148765Z","time spent":"1.332046456s","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T21:42:11.480825Z","caller":"traceutil/trace.go:171","msg":"trace[848966484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:827; }","duration":"1.103048078s","start":"2026-02-22T21:42:10.377765Z","end":"2026-02-22T21:42:11.480813Z","steps":["trace[848966484] 'agreement among raft nodes before linearized reading' (duration: 1.102896725s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.480847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:10.533504Z","time spent":"947.259992ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:11.480863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:10.377735Z","time spent":"1.10312023s","remote":"127.0.0.1:52188","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-22T21:42:11.480886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.895985ms","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-22T21:42:11.480956Z","caller":"traceutil/trace.go:171","msg":"trace[309532771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:827; }","duration":"343.987428ms","start":"2026-02-22T21:42:11.13695Z","end":"2026-02-22T21:42:11.480937Z","steps":["trace[309532771] 'agreement among raft nodes before linearized reading' (duration: 343.833665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.480991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:11.136938Z","time spent":"344.045189ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T21:42:11.481117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.770734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-22T21:42:11.481127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.676377ms","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-22T21:42:11.481142Z","caller":"traceutil/trace.go:171","msg":"trace[1656666620] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:827; }","duration":"998.835956ms","start":"2026-02-22T21:42:10.4823Z","end":"2026-02-22T21:42:11.481136Z","steps":["trace[1656666620] 'agreement among raft nodes before linearized reading' (duration: 998.791064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.481158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:10.482279Z","time spent":"998.874317ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-22T21:42:11.48116Z","caller":"traceutil/trace.go:171","msg":"trace[1671648636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:827; }","duration":"345.74832ms","start":"2026-02-22T21:42:11.1354Z","end":"2026-02-22T21:42:11.481148Z","steps":["trace[1671648636] 'agreement among raft nodes before linearized reading' (duration: 345.664687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.481169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.494946ms","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":"warn","ts":"2026-02-22T21:42:11.481186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:11.135378Z","time spent":"345.80254ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-22T21:42:11.481199Z","caller":"traceutil/trace.go:171","msg":"trace[1723699963] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:827; }","duration":"339.549438ms","start":"2026-02-22T21:42:11.141641Z","end":"2026-02-22T21:42:11.48119Z","steps":["trace[1723699963] 'agreement among raft nodes before linearized reading' (duration: 339.492676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.481228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:11.141628Z","time spent":"339.592848ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-22T21:42:11.481451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.700621ms","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-22T21:42:11.481552Z","caller":"traceutil/trace.go:171","msg":"trace[269994697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:827; }","duration":"406.838863ms","start":"2026-02-22T21:42:11.074692Z","end":"2026-02-22T21:42:11.48153Z","steps":["trace[269994697] 'agreement among raft nodes before linearized reading' (duration: 406.547137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:11.481598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:11.074678Z","time spent":"406.911686ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T21:42:24.073314Z","caller":"traceutil/trace.go:171","msg":"trace[540287725] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"451.639461ms","start":"2026-02-22T21:42:23.621651Z","end":"2026-02-22T21:42:24.07329Z","steps":["trace[540287725] 'process raft request' (duration: 451.487227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:24.073466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:23.621635Z","time spent":"451.747214ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:42:24.073853Z","caller":"traceutil/trace.go:171","msg":"trace[741236411] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:904; }","duration":"220.755589ms","start":"2026-02-22T21:42:23.853072Z","end":"2026-02-22T21:42:24.073828Z","steps":["trace[741236411] 'read index received' (duration: 220.030751ms)","trace[741236411] 'applied index is now lower than readState.Index' (duration: 723.808µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:24.073966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.885842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:42:24.073999Z","caller":"traceutil/trace.go:171","msg":"trace[1547383117] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:864; }","duration":"220.980904ms","start":"2026-02-22T21:42:23.853009Z","end":"2026-02-22T21:42:24.07399Z","steps":["trace[1547383117] 'agreement among raft nodes before linearized reading' (duration: 220.906423ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:24.074012Z","caller":"traceutil/trace.go:171","msg":"trace[822162496] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"450.570696ms","start":"2026-02-22T21:42:23.623326Z","end":"2026-02-22T21:42:24.073897Z","steps":["trace[822162496] 'process raft request' (duration: 450.390602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:24.074084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:23.623288Z","time spent":"450.756901ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:27.580929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.89956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:42:27.581038Z","caller":"traceutil/trace.go:171","msg":"trace[645390896] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"432.082062ms","start":"2026-02-22T21:42:27.14894Z","end":"2026-02-22T21:42:27.581022Z","steps":["trace[645390896] 'read index received' (duration: 312.923856ms)","trace[645390896] 'applied index is now lower than readState.Index' (duration: 119.157036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:27.58112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.186205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:42:27.581185Z","caller":"traceutil/trace.go:171","msg":"trace[555400737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"432.289947ms","start":"2026-02-22T21:42:27.148886Z","end":"2026-02-22T21:42:27.581176Z","steps":["trace[555400737] 'agreement among raft nodes before linearized reading' (duration: 432.180935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:27.581226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.148864Z","time spent":"432.354149ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T21:42:27.5813Z","caller":"traceutil/trace.go:171","msg":"trace[1717806045] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"511.917865ms","start":"2026-02-22T21:42:27.069346Z","end":"2026-02-22T21:42:27.581264Z","steps":["trace[1717806045] 'process raft request' (duration: 392.601395ms)","trace[1717806045] 'compare' (duration: 118.752287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:27.581462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.069328Z","time spent":"512.039487ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11172,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:27.892708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.383772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:42:27.892982Z","caller":"traceutil/trace.go:171","msg":"trace[1427453690] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"304.081984ms","start":"2026-02-22T21:42:27.588855Z","end":"2026-02-22T21:42:27.892937Z","steps":["trace[1427453690] 'process raft request' (duration: 93.362624ms)","trace[1427453690] 'compare' (duration: 210.262669ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:42:27.893054Z","caller":"traceutil/trace.go:171","msg":"trace[1212708639] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:931; }","duration":"265.303303ms","start":"2026-02-22T21:42:27.627733Z","end":"2026-02-22T21:42:27.893037Z","steps":["trace[1212708639] 'read index received' (duration: 54.458671ms)","trace[1212708639] 'applied index is now lower than readState.Index' (duration: 210.843112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:27.89322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.588838Z","time spent":"304.285599ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:42:27.893315Z","caller":"traceutil/trace.go:171","msg":"trace[41707732] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"301.276209ms","start":"2026-02-22T21:42:27.592028Z","end":"2026-02-22T21:42:27.893304Z","steps":["trace[41707732] 'process raft request' (duration: 300.933311ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:27.893329Z","caller":"traceutil/trace.go:171","msg":"trace[1354956763] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"304.382241ms","start":"2026-02-22T21:42:27.588899Z","end":"2026-02-22T21:42:27.893281Z","steps":["trace[1354956763] 'process raft request' (duration: 303.965711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:27.893403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.592016Z","time spent":"301.33567ms","remote":"127.0.0.1:52424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:27.893423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.588879Z","time spent":"304.494214ms","remote":"127.0.0.1:52204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:27.893853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.138253ms","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-22T21:42:27.894025Z","caller":"traceutil/trace.go:171","msg":"trace[1042242654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"266.209025ms","start":"2026-02-22T21:42:27.62767Z","end":"2026-02-22T21:42:27.893879Z","steps":["trace[1042242654] 'agreement among raft nodes before linearized reading' (duration: 265.854327ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:27.893547Z","caller":"traceutil/trace.go:171","msg":"trace[1010489767] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"303.782086ms","start":"2026-02-22T21:42:27.589569Z","end":"2026-02-22T21:42:27.893351Z","steps":["trace[1010489767] 'process raft request' (duration: 303.354736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:27.894451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.589559Z","time spent":"304.841582ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:28.235257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.814498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:42:28.235388Z","caller":"traceutil/trace.go:171","msg":"trace[93327545] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"150.109819ms","start":"2026-02-22T21:42:28.08526Z","end":"2026-02-22T21:42:28.23537Z","steps":["trace[93327545] 'read index received' (duration: 71.692µs)","trace[93327545] 'applied index is now lower than readState.Index' (duration: 150.036727ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:42:28.235493Z","caller":"traceutil/trace.go:171","msg":"trace[994776405] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"335.736627ms","start":"2026-02-22T21:42:27.89973Z","end":"2026-02-22T21:42:28.235466Z","steps":["trace[994776405] 'process raft request' (duration: 104.615042ms)","trace[994776405] 'compare' (duration: 230.643634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:28.235597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.342494ms","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-22T21:42:28.235637Z","caller":"traceutil/trace.go:171","msg":"trace[305895940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"150.418136ms","start":"2026-02-22T21:42:28.085208Z","end":"2026-02-22T21:42:28.235626Z","steps":["trace[305895940] 'agreement among raft nodes before linearized reading' (duration: 150.251092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:28.23563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:27.899714Z","time spent":"335.83805ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T21:42:28.235642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.946368ms","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-22T21:42:28.235714Z","caller":"traceutil/trace.go:171","msg":"trace[286602418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"148.129582ms","start":"2026-02-22T21:42:28.087568Z","end":"2026-02-22T21:42:28.235698Z","steps":["trace[286602418] 'agreement among raft nodes before linearized reading' (duration: 147.959588ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:29.315431Z","caller":"traceutil/trace.go:171","msg":"trace[1854086352] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"237.238302ms","start":"2026-02-22T21:42:29.078167Z","end":"2026-02-22T21:42:29.315405Z","steps":["trace[1854086352] 'read index received' (duration: 237.015487ms)","trace[1854086352] 'applied index is now lower than readState.Index' (duration: 222.075µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:42:29.315576Z","caller":"traceutil/trace.go:171","msg":"trace[31425716] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"265.310723ms","start":"2026-02-22T21:42:29.05022Z","end":"2026-02-22T21:42:29.31553Z","steps":["trace[31425716] 'process raft request' (duration: 265.022416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:29.315733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.401202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:42:29.315797Z","caller":"traceutil/trace.go:171","msg":"trace[1165358955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"167.496675ms","start":"2026-02-22T21:42:29.148275Z","end":"2026-02-22T21:42:29.315772Z","steps":["trace[1165358955] 'agreement among raft nodes before linearized reading' (duration: 167.408613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:29.315611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.413266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:42:29.315947Z","caller":"traceutil/trace.go:171","msg":"trace[65848675] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:896; }","duration":"237.806976ms","start":"2026-02-22T21:42:29.078128Z","end":"2026-02-22T21:42:29.315935Z","steps":["trace[65848675] 'agreement among raft nodes before linearized reading' (duration: 237.421777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:30.076062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.854477ms","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-22T21:42:30.076149Z","caller":"traceutil/trace.go:171","msg":"trace[1919204382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"163.99482ms","start":"2026-02-22T21:42:29.912136Z","end":"2026-02-22T21:42:30.076131Z","steps":["trace[1919204382] 'range keys from in-memory index tree' (duration: 163.680013ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:30.417598Z","caller":"traceutil/trace.go:171","msg":"trace[522607706] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"133.295977ms","start":"2026-02-22T21:42:30.284281Z","end":"2026-02-22T21:42:30.417577Z","steps":["trace[522607706] 'process raft request' (duration: 74.160353ms)","trace[522607706] 'compare' (duration: 59.046482ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:42:30.504433Z","caller":"traceutil/trace.go:171","msg":"trace[947035388] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"215.436811ms","start":"2026-02-22T21:42:30.288977Z","end":"2026-02-22T21:42:30.504414Z","steps":["trace[947035388] 'process raft request' (duration: 215.40392ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:30.504485Z","caller":"traceutil/trace.go:171","msg":"trace[1052384571] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"217.999462ms","start":"2026-02-22T21:42:30.286462Z","end":"2026-02-22T21:42:30.504461Z","steps":["trace[1052384571] 'process raft request' (duration: 217.823378ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:31.530749Z","caller":"traceutil/trace.go:171","msg":"trace[1526858049] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"206.284552ms","start":"2026-02-22T21:42:31.32444Z","end":"2026-02-22T21:42:31.530724Z","steps":["trace[1526858049] 'process raft request' (duration: 206.133678ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:32.128392Z","caller":"traceutil/trace.go:171","msg":"trace[830418472] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"429.878298ms","start":"2026-02-22T21:42:31.698493Z","end":"2026-02-22T21:42:32.128371Z","steps":["trace[830418472] 'process raft request' (duration: 329.976189ms)","trace[830418472] 'compare' (duration: 99.804737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:32.128501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:31.698475Z","time spent":"429.96951ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:42:32.611029Z","caller":"traceutil/trace.go:171","msg":"trace[61361106] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"500.344262ms","start":"2026-02-22T21:42:32.110654Z","end":"2026-02-22T21:42:32.610998Z","steps":["trace[61361106] 'read index received' (duration: 448.561914ms)","trace[61361106] 'applied index is now lower than readState.Index' (duration: 51.781088ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T21:42:32.611049Z","caller":"traceutil/trace.go:171","msg":"trace[595866374] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"577.273661ms","start":"2026-02-22T21:42:32.033744Z","end":"2026-02-22T21:42:32.611018Z","steps":["trace[595866374] 'process raft request' (duration: 525.532295ms)","trace[595866374] 'compare' (duration: 51.586113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:32.611254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.579367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T21:42:32.611335Z","caller":"traceutil/trace.go:171","msg":"trace[195830651] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:905; }","duration":"500.66227ms","start":"2026-02-22T21:42:32.110627Z","end":"2026-02-22T21:42:32.611289Z","steps":["trace[195830651] 'agreement among raft nodes before linearized reading' (duration: 500.538417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:32.61138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.110611Z","time spent":"500.758552ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-22T21:42:32.611486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.033726Z","time spent":"577.510957ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T21:42:32.611749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.705009ms","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-22T21:42:32.611805Z","caller":"traceutil/trace.go:171","msg":"trace[745589520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"456.782842ms","start":"2026-02-22T21:42:32.155007Z","end":"2026-02-22T21:42:32.61179Z","steps":["trace[745589520] 'agreement among raft nodes before linearized reading' (duration: 456.616628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:32.611852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.154996Z","time spent":"456.845793ms","remote":"127.0.0.1:52188","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-22T21:42:32.612196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.647273ms","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-22T21:42:32.612251Z","caller":"traceutil/trace.go:171","msg":"trace[1881585620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"104.735735ms","start":"2026-02-22T21:42:32.507502Z","end":"2026-02-22T21:42:32.612237Z","steps":["trace[1881585620] 'agreement among raft nodes before linearized reading' (duration: 104.583471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:32.612515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.069808ms","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-22T21:42:32.612567Z","caller":"traceutil/trace.go:171","msg":"trace[115409793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"191.159622ms","start":"2026-02-22T21:42:32.421395Z","end":"2026-02-22T21:42:32.612555Z","steps":["trace[115409793] 'agreement among raft nodes before linearized reading' (duration: 191.025298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:32.612728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.854773ms","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-22T21:42:32.612811Z","caller":"traceutil/trace.go:171","msg":"trace[209903596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:905; }","duration":"328.972986ms","start":"2026-02-22T21:42:32.283824Z","end":"2026-02-22T21:42:32.612797Z","steps":["trace[209903596] 'agreement among raft nodes before linearized reading' (duration: 328.678959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:32.6129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.283804Z","time spent":"329.084528ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T21:42:32.850603Z","caller":"traceutil/trace.go:171","msg":"trace[1031074301] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"230.553593ms","start":"2026-02-22T21:42:32.620012Z","end":"2026-02-22T21:42:32.850566Z","steps":["trace[1031074301] 'process raft request' (duration: 230.471671ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:32.850621Z","caller":"traceutil/trace.go:171","msg":"trace[1441560137] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"231.128167ms","start":"2026-02-22T21:42:32.619469Z","end":"2026-02-22T21:42:32.850597Z","steps":["trace[1441560137] 'process raft request' (duration: 230.876811ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:32.850897Z","caller":"traceutil/trace.go:171","msg":"trace[1647323140] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"230.693426ms","start":"2026-02-22T21:42:32.620191Z","end":"2026-02-22T21:42:32.850884Z","steps":["trace[1647323140] 'process raft request' (duration: 230.329738ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:32.850924Z","caller":"traceutil/trace.go:171","msg":"trace[1202916164] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"173.97767ms","start":"2026-02-22T21:42:32.676923Z","end":"2026-02-22T21:42:32.850901Z","steps":["trace[1202916164] 'process raft request' (duration: 173.943489ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:32.851039Z","caller":"traceutil/trace.go:171","msg":"trace[848191580] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"230.785858ms","start":"2026-02-22T21:42:32.620248Z","end":"2026-02-22T21:42:32.851034Z","steps":["trace[848191580] 'process raft request' (duration: 230.544742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:33.239653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.326255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-22T21:42:33.240061Z","caller":"traceutil/trace.go:171","msg":"trace[1959761206] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"380.467887ms","start":"2026-02-22T21:42:32.859574Z","end":"2026-02-22T21:42:33.240042Z","steps":["trace[1959761206] 'process raft request' (duration: 106.693831ms)","trace[1959761206] 'compare' (duration: 273.145271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:33.240127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.859562Z","time spent":"380.539008ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T21:42:33.240141Z","caller":"traceutil/trace.go:171","msg":"trace[1618228110] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"380.536088ms","start":"2026-02-22T21:42:32.859578Z","end":"2026-02-22T21:42:33.240114Z","steps":["trace[1618228110] 'process raft request' (duration: 380.1644ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:33.24017Z","caller":"traceutil/trace.go:171","msg":"trace[170089039] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"377.2584ms","start":"2026-02-22T21:42:32.862895Z","end":"2026-02-22T21:42:33.240153Z","steps":["trace[170089039] 'process raft request' (duration: 376.904202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:33.240283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.859566Z","time spent":"380.647071ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T21:42:33.240285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:32.862886Z","time spent":"377.349982ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:42:33.813773Z","caller":"traceutil/trace.go:171","msg":"trace[925535035] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"276.137983ms","start":"2026-02-22T21:42:33.537594Z","end":"2026-02-22T21:42:33.813732Z","steps":["trace[925535035] 'process raft request' (duration: 275.898846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:35.085919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.233599ms","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-22T21:42:35.085995Z","caller":"traceutil/trace.go:171","msg":"trace[1439580591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"231.363102ms","start":"2026-02-22T21:42:34.854616Z","end":"2026-02-22T21:42:35.085979Z","steps":["trace[1439580591] 'range keys from in-memory index tree' (duration: 231.061865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:35.086083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.097805ms","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-22T21:42:35.086165Z","caller":"traceutil/trace.go:171","msg":"trace[2027378648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"231.219308ms","start":"2026-02-22T21:42:34.854925Z","end":"2026-02-22T21:42:35.086145Z","steps":["trace[2027378648] 'range keys from in-memory index tree' (duration: 230.794238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:35.086441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.020803ms","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-22T21:42:35.086714Z","caller":"traceutil/trace.go:171","msg":"trace[1049499281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:916; }","duration":"231.363372ms","start":"2026-02-22T21:42:34.855333Z","end":"2026-02-22T21:42:35.086696Z","steps":["trace[1049499281] 'range keys from in-memory index tree' (duration: 230.254796ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:41.031879Z","caller":"traceutil/trace.go:171","msg":"trace[1484821706] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"271.806117ms","start":"2026-02-22T21:42:40.760025Z","end":"2026-02-22T21:42:41.031831Z","steps":["trace[1484821706] 'process raft request' (duration: 271.666954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:41.490558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.027839ms","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-22T21:42:41.49063Z","caller":"traceutil/trace.go:171","msg":"trace[604511570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"377.133971ms","start":"2026-02-22T21:42:41.113482Z","end":"2026-02-22T21:42:41.490616Z","steps":["trace[604511570] 'range keys from in-memory index tree' (duration: 376.910626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:41.49066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:41.113477Z","time spent":"377.177012ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T21:42:41.49076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.482182ms","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-22T21:42:41.490815Z","caller":"traceutil/trace.go:171","msg":"trace[859752854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"375.566223ms","start":"2026-02-22T21:42:41.115236Z","end":"2026-02-22T21:42:41.490802Z","steps":["trace[859752854] 'range keys from in-memory index tree' (duration: 375.333668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:41.490798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.625408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-22T21:42:41.490844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:41.115225Z","time spent":"375.612594ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-22T21:42:41.490864Z","caller":"traceutil/trace.go:171","msg":"trace[291028928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"381.72848ms","start":"2026-02-22T21:42:41.109119Z","end":"2026-02-22T21:42:41.490847Z","steps":["trace[291028928] 'range keys from in-memory index tree' (duration: 381.486995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:41.4909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:41.109101Z","time spent":"381.791301ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T21:42:41.683735Z","caller":"traceutil/trace.go:171","msg":"trace[426505598] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"189.601555ms","start":"2026-02-22T21:42:41.494111Z","end":"2026-02-22T21:42:41.683712Z","steps":["trace[426505598] 'process raft request' (duration: 189.438772ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T21:42:41.80096Z","caller":"traceutil/trace.go:171","msg":"trace[1728257863] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"306.618079ms","start":"2026-02-22T21:42:41.494324Z","end":"2026-02-22T21:42:41.800942Z","steps":["trace[1728257863] 'process raft request' (duration: 306.578998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:42:41.801069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:41.494318Z","time spent":"306.69576ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:42:41.801231Z","caller":"traceutil/trace.go:171","msg":"trace[2007671179] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"306.946296ms","start":"2026-02-22T21:42:41.494264Z","end":"2026-02-22T21:42:41.801211Z","steps":["trace[2007671179] 'process raft request' (duration: 233.564145ms)","trace[2007671179] 'compare' (duration: 72.820808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T21:42:41.801336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T21:42:41.494248Z","time spent":"307.032579ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T21:45:41.975373Z","caller":"traceutil/trace.go:171","msg":"trace[1732261243] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"101.708334ms","start":"2026-02-22T21:45:41.873642Z","end":"2026-02-22T21:45:41.975351Z","steps":["trace[1732261243] 'process raft request' (duration: 101.562121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:45:50.178067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.639011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-22T21:45:50.178206Z","caller":"traceutil/trace.go:171","msg":"trace[1873986286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1770; }","duration":"173.845617ms","start":"2026-02-22T21:45:50.004341Z","end":"2026-02-22T21:45:50.178187Z","steps":["trace[1873986286] 'range keys from in-memory index tree' (duration: 173.401087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T21:45:50.178817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.42051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T21:45:50.179072Z","caller":"traceutil/trace.go:171","msg":"trace[1500141914] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1770; }","duration":"124.498296ms","start":"2026-02-22T21:45:50.054546Z","end":"2026-02-22T21:45:50.179044Z","steps":["trace[1500141914] 'count revisions from in-memory index tree' (duration: 123.303597ms)"],"step_count":1}