{"level":"warn","ts":"2026-02-17T06:33:49.340091Z","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-17T06:33:49.341557Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-17T06:33:49.341685Z","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-17T06:33:49.341727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-17T06:33:49.341765Z","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-17T06:33:49.345195Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-17T06:33:49.348679Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-17T06:33:49.352684Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.750741ms"} {"level":"info","ts":"2026-02-17T06:33:49.363073Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-17T06:33:49.363172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T06:33:49.363221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-17T06:33:49.363292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T06:33:49.363309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-17T06:33:49.363365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-17T06:33:49.373305Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T06:33:49.381014Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T06:33:49.390417Z","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-17T06:33:49.398377Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T06:33:49.401253Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T06:33:49.411289Z","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-17T06:33:49.413142Z","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-17T06:33:49.414535Z","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-17T06:33:49.414785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-17T06:33:49.416571Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-17T06:33:49.421126Z","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-17T06:33:49.421295Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-17T06:33:49.422397Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-17T06:33:49.422366Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T06:33:49.422385Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T06:33:49.464028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T06:33:49.464116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T06:33:49.46415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-17T06:33:49.464171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-17T06:33:49.464206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-17T06:33:49.464228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-17T06:33:49.464244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-17T06:33:49.46788Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T06:33:49.468027Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T06:33:49.468322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T06:33:49.468585Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:33:49.469923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T06:33:49.47101Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T06:33:49.471514Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T06:33:49.471591Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:33:49.47173Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:33:49.471752Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T06:33:49.489583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-17T06:33:53.676918Z","caller":"traceutil/trace.go:171","msg":"trace[320337764] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"187.353794ms","start":"2026-02-17T06:33:53.489542Z","end":"2026-02-17T06:33:53.676896Z","steps":["trace[320337764] 'process raft request' (duration: 181.873541ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:00.209347Z","caller":"traceutil/trace.go:171","msg":"trace[1268815170] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"171.984053ms","start":"2026-02-17T06:34:00.037346Z","end":"2026-02-17T06:34:00.20933Z","steps":["trace[1268815170] 'process raft request' (duration: 171.948322ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:00.209669Z","caller":"traceutil/trace.go:171","msg":"trace[325633366] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"172.527826ms","start":"2026-02-17T06:34:00.037135Z","end":"2026-02-17T06:34:00.209662Z","steps":["trace[325633366] 'process raft request' (duration: 166.91375ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:00.209832Z","caller":"traceutil/trace.go:171","msg":"trace[1946002846] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"172.514736ms","start":"2026-02-17T06:34:00.037311Z","end":"2026-02-17T06:34:00.209826Z","steps":["trace[1946002846] 'process raft request' (duration: 171.949202ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:00.210211Z","caller":"traceutil/trace.go:171","msg":"trace[584859389] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:379; }","duration":"149.778577ms","start":"2026-02-17T06:34:00.060425Z","end":"2026-02-17T06:34:00.210204Z","steps":["trace[584859389] 'read index received' (duration: 143.632358ms)","trace[584859389] 'applied index is now lower than readState.Index' (duration: 6.145739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:00.210285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.835838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-17T06:34:00.210333Z","caller":"traceutil/trace.go:171","msg":"trace[1498660780] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"149.91314ms","start":"2026-02-17T06:34:00.06039Z","end":"2026-02-17T06:34:00.210303Z","steps":["trace[1498660780] 'agreement among raft nodes before linearized reading' (duration: 149.839668ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:00.210485Z","caller":"traceutil/trace.go:171","msg":"trace[2068248017] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"157.79394ms","start":"2026-02-17T06:34:00.052685Z","end":"2026-02-17T06:34:00.210479Z","steps":["trace[2068248017] 'process raft request' (duration: 157.480723ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:05.92894Z","caller":"traceutil/trace.go:171","msg":"trace[1683192793] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"441.405518ms","start":"2026-02-17T06:34:05.487512Z","end":"2026-02-17T06:34:05.928917Z","steps":["trace[1683192793] 'process raft request' (duration: 441.233284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:05.931288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:05.48747Z","time spent":"441.8938ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4048,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:06.598707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.830082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:34:06.598818Z","caller":"traceutil/trace.go:171","msg":"trace[855273619] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"884.289441ms","start":"2026-02-17T06:34:05.714511Z","end":"2026-02-17T06:34:06.598801Z","steps":["trace[855273619] 'read index received' (duration: 214.459418ms)","trace[855273619] 'applied index is now lower than readState.Index' (duration: 669.828983ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:06.599536Z","caller":"traceutil/trace.go:171","msg":"trace[1651726529] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.00227005s","start":"2026-02-17T06:34:05.597221Z","end":"2026-02-17T06:34:06.599491Z","steps":["trace[1651726529] 'process raft request' (duration: 553.464954ms)","trace[1651726529] 'compare' (duration: 447.242538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:06.59976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:05.597201Z","time spent":"1.002431984s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:06.601232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.71111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-17T06:34:06.601302Z","caller":"traceutil/trace.go:171","msg":"trace[981172484] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:415; }","duration":"886.811642ms","start":"2026-02-17T06:34:05.714476Z","end":"2026-02-17T06:34:06.601288Z","steps":["trace[981172484] 'agreement among raft nodes before linearized reading' (duration: 886.675889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:06.60134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:05.714457Z","time spent":"886.874574ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-17T06:34:06.601396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.351116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:06.60143Z","caller":"traceutil/trace.go:171","msg":"trace[1584296977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"588.423727ms","start":"2026-02-17T06:34:06.012997Z","end":"2026-02-17T06:34:06.60142Z","steps":["trace[1584296977] 'agreement among raft nodes before linearized reading' (duration: 588.348176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:06.60146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:06.012978Z","time spent":"588.476568ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:06.601628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.991201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"warn","ts":"2026-02-17T06:34:06.601648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.135584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T06:34:06.601656Z","caller":"traceutil/trace.go:171","msg":"trace[1178794454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"372.076363ms","start":"2026-02-17T06:34:06.22957Z","end":"2026-02-17T06:34:06.601647Z","steps":["trace[1178794454] 'agreement among raft nodes before linearized reading' (duration: 372.017132ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:06.601676Z","caller":"traceutil/trace.go:171","msg":"trace[272851775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"555.184304ms","start":"2026-02-17T06:34:06.046483Z","end":"2026-02-17T06:34:06.601667Z","steps":["trace[272851775] 'agreement among raft nodes before linearized reading' (duration: 555.133743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:06.601687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:06.229538Z","time spent":"372.142705ms","remote":"127.0.0.1:58322","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":"warn","ts":"2026-02-17T06:34:06.601701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:06.046472Z","time spent":"555.222316ms","remote":"127.0.0.1:58322","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-17T06:34:14.921582Z","caller":"traceutil/trace.go:171","msg":"trace[1353388184] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"202.907329ms","start":"2026-02-17T06:34:14.718647Z","end":"2026-02-17T06:34:14.921555Z","steps":["trace[1353388184] 'process raft request' (duration: 131.110495ms)","trace[1353388184] 'compare' (duration: 71.471706ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:22.511854Z","caller":"traceutil/trace.go:171","msg":"trace[1491667809] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"241.749027ms","start":"2026-02-17T06:34:22.270077Z","end":"2026-02-17T06:34:22.511826Z","steps":["trace[1491667809] 'process raft request' (duration: 241.558443ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:25.23092Z","caller":"traceutil/trace.go:171","msg":"trace[1226382050] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"443.074938ms","start":"2026-02-17T06:34:24.787817Z","end":"2026-02-17T06:34:25.230892Z","steps":["trace[1226382050] 'read index received' (duration: 442.831322ms)","trace[1226382050] 'applied index is now lower than readState.Index' (duration: 242.566µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:25.231322Z","caller":"traceutil/trace.go:171","msg":"trace[1740793307] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"444.511963ms","start":"2026-02-17T06:34:24.786799Z","end":"2026-02-17T06:34:25.231311Z","steps":["trace[1740793307] 'process raft request' (duration: 443.930719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:25.231451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:24.786782Z","time spent":"444.566284ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:25.231709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.887708ms","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-17T06:34:25.231734Z","caller":"traceutil/trace.go:171","msg":"trace[167136422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"443.923368ms","start":"2026-02-17T06:34:24.787801Z","end":"2026-02-17T06:34:25.231725Z","steps":["trace[167136422] 'agreement among raft nodes before linearized reading' (duration: 443.803575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:25.231753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:24.787794Z","time spent":"443.95519ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:25.231846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.403469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:25.231862Z","caller":"traceutil/trace.go:171","msg":"trace[952972008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"393.47782ms","start":"2026-02-17T06:34:24.838379Z","end":"2026-02-17T06:34:25.231857Z","steps":["trace[952972008] 'agreement among raft nodes before linearized reading' (duration: 393.44892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:25.23188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:24.838351Z","time spent":"393.522122ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:34:25.232035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.360877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:25.232051Z","caller":"traceutil/trace.go:171","msg":"trace[1618164345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"260.387857ms","start":"2026-02-17T06:34:24.971658Z","end":"2026-02-17T06:34:25.232046Z","steps":["trace[1618164345] 'agreement among raft nodes before linearized reading' (duration: 260.337136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:25.232155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.666744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:34:25.23217Z","caller":"traceutil/trace.go:171","msg":"trace[1492209986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"265.711856ms","start":"2026-02-17T06:34:24.966454Z","end":"2026-02-17T06:34:25.232166Z","steps":["trace[1492209986] 'agreement among raft nodes before linearized reading' (duration: 265.659545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:25.573414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.334777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:34:25.573558Z","caller":"traceutil/trace.go:171","msg":"trace[503823545] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"330.796842ms","start":"2026-02-17T06:34:25.242742Z","end":"2026-02-17T06:34:25.573538Z","steps":["trace[503823545] 'process raft request' (duration: 225.192079ms)","trace[503823545] 'compare' (duration: 105.160613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:25.574421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:25.242714Z","time spent":"330.892035ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:26.185186Z","caller":"traceutil/trace.go:171","msg":"trace[805629323] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"343.380807ms","start":"2026-02-17T06:34:25.841771Z","end":"2026-02-17T06:34:26.185152Z","steps":["trace[805629323] 'read index received' (duration: 276.123169ms)","trace[805629323] 'applied index is now lower than readState.Index' (duration: 67.256188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:26.185361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.558911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:26.185388Z","caller":"traceutil/trace.go:171","msg":"trace[308846807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"343.661223ms","start":"2026-02-17T06:34:25.841719Z","end":"2026-02-17T06:34:26.18538Z","steps":["trace[308846807] 'agreement among raft nodes before linearized reading' (duration: 343.54655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:26.185416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:25.841693Z","time spent":"343.716955ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T06:34:26.18565Z","caller":"traceutil/trace.go:171","msg":"trace[1591308015] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"942.329789ms","start":"2026-02-17T06:34:25.24331Z","end":"2026-02-17T06:34:26.185639Z","steps":["trace[1591308015] 'process raft request' (duration: 874.651511ms)","trace[1591308015] 'compare' (duration: 66.656404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:26.185725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:25.243301Z","time spent":"942.366921ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:26.185807Z","caller":"traceutil/trace.go:171","msg":"trace[366709295] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"941.548791ms","start":"2026-02-17T06:34:25.243631Z","end":"2026-02-17T06:34:26.185799Z","steps":["trace[366709295] 'process raft request' (duration: 940.844244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:26.185838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:25.243624Z","time spent":"942.194916ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:28.325642Z","caller":"traceutil/trace.go:171","msg":"trace[1832154659] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"113.959841ms","start":"2026-02-17T06:34:28.211634Z","end":"2026-02-17T06:34:28.325594Z","steps":["trace[1832154659] 'process raft request' (duration: 113.688324ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:28.334708Z","caller":"traceutil/trace.go:171","msg":"trace[360388312] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"119.528961ms","start":"2026-02-17T06:34:28.215117Z","end":"2026-02-17T06:34:28.334646Z","steps":["trace[360388312] 'process raft request' (duration: 119.318726ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:31.57839Z","caller":"traceutil/trace.go:171","msg":"trace[672775403] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"119.134223ms","start":"2026-02-17T06:34:31.459222Z","end":"2026-02-17T06:34:31.578357Z","steps":["trace[672775403] 'read index received' (duration: 118.848147ms)","trace[672775403] 'applied index is now lower than readState.Index' (duration: 284.756µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:31.57877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.505282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r4d8k\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T06:34:31.578819Z","caller":"traceutil/trace.go:171","msg":"trace[1075655832] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r4d8k; range_end:; response_count:1; response_revision:537; }","duration":"119.610444ms","start":"2026-02-17T06:34:31.459196Z","end":"2026-02-17T06:34:31.578807Z","steps":["trace[1075655832] 'agreement among raft nodes before linearized reading' (duration: 119.285336ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:31.579253Z","caller":"traceutil/trace.go:171","msg":"trace[2135454684] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"312.262132ms","start":"2026-02-17T06:34:31.266977Z","end":"2026-02-17T06:34:31.579239Z","steps":["trace[2135454684] 'process raft request' (duration: 311.201487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:31.579378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:31.266968Z","time spent":"312.321623ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:31.854725Z","caller":"traceutil/trace.go:171","msg":"trace[1970201229] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"240.475197ms","start":"2026-02-17T06:34:31.614231Z","end":"2026-02-17T06:34:31.854706Z","steps":["trace[1970201229] 'read index received' (duration: 240.310513ms)","trace[1970201229] 'applied index is now lower than readState.Index' (duration: 163.994µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:31.854822Z","caller":"traceutil/trace.go:171","msg":"trace[576551610] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"261.239298ms","start":"2026-02-17T06:34:31.593575Z","end":"2026-02-17T06:34:31.854815Z","steps":["trace[576551610] 'process raft request' (duration: 260.975782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:31.855076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.838146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:31.855102Z","caller":"traceutil/trace.go:171","msg":"trace[358546414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"240.912867ms","start":"2026-02-17T06:34:31.614179Z","end":"2026-02-17T06:34:31.855092Z","steps":["trace[358546414] 'agreement among raft nodes before linearized reading' (duration: 240.788465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:32.58692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.817254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r4d8k\" ","response":"range_response_count:1 size:16818"} {"level":"info","ts":"2026-02-17T06:34:32.586995Z","caller":"traceutil/trace.go:171","msg":"trace[1666794282] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r4d8k; range_end:; response_count:1; response_revision:541; }","duration":"120.928736ms","start":"2026-02-17T06:34:32.466048Z","end":"2026-02-17T06:34:32.586976Z","steps":["trace[1666794282] 'range keys from in-memory index tree' (duration: 120.663941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:32.587574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.826004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:34:32.587641Z","caller":"traceutil/trace.go:171","msg":"trace[418879891] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"119.673037ms","start":"2026-02-17T06:34:32.467956Z","end":"2026-02-17T06:34:32.587629Z","steps":["trace[418879891] 'process raft request' (duration: 12.705889ms)","trace[418879891] 'compare' (duration: 106.681181ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:34.090374Z","caller":"traceutil/trace.go:171","msg":"trace[1337082532] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"249.586419ms","start":"2026-02-17T06:34:33.840769Z","end":"2026-02-17T06:34:34.090356Z","steps":["trace[1337082532] 'read index received' (duration: 249.457596ms)","trace[1337082532] 'applied index is now lower than readState.Index' (duration: 128.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:34.090527Z","caller":"traceutil/trace.go:171","msg":"trace[1355285275] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"373.750723ms","start":"2026-02-17T06:34:33.716769Z","end":"2026-02-17T06:34:34.09052Z","steps":["trace[1355285275] 'process raft request' (duration: 373.504087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.090602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:33.716759Z","time spent":"373.786914ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T06:34:34.09077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.995658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.090791Z","caller":"traceutil/trace.go:171","msg":"trace[1365559135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"250.06135ms","start":"2026-02-17T06:34:33.840723Z","end":"2026-02-17T06:34:34.090784Z","steps":["trace[1365559135] 'agreement among raft nodes before linearized reading' (duration: 249.998018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.090937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.695052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:34.090954Z","caller":"traceutil/trace.go:171","msg":"trace[2096911523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"219.735493ms","start":"2026-02-17T06:34:33.871214Z","end":"2026-02-17T06:34:34.090949Z","steps":["trace[2096911523] 'agreement among raft nodes before linearized reading' (duration: 219.662641ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:34.386142Z","caller":"traceutil/trace.go:171","msg":"trace[386952219] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"291.263264ms","start":"2026-02-17T06:34:34.094865Z","end":"2026-02-17T06:34:34.386129Z","steps":["trace[386952219] 'process raft request' (duration: 285.534678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.386398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.111555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.386423Z","caller":"traceutil/trace.go:171","msg":"trace[1822476703] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"284.184607ms","start":"2026-02-17T06:34:34.102232Z","end":"2026-02-17T06:34:34.386417Z","steps":["trace[1822476703] 'agreement among raft nodes before linearized reading' (duration: 284.137725ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:34.387089Z","caller":"traceutil/trace.go:171","msg":"trace[518121311] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"283.699295ms","start":"2026-02-17T06:34:34.102275Z","end":"2026-02-17T06:34:34.385974Z","steps":["trace[518121311] 'read index received' (duration: 278.090182ms)","trace[518121311] 'applied index is now lower than readState.Index' (duration: 5.608283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:34.389358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.043664ms","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-17T06:34:34.389381Z","caller":"traceutil/trace.go:171","msg":"trace[2086802719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:557; }","duration":"287.077935ms","start":"2026-02-17T06:34:34.102297Z","end":"2026-02-17T06:34:34.389375Z","steps":["trace[2086802719] 'agreement among raft nodes before linearized reading' (duration: 287.010973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.389608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.1903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.389628Z","caller":"traceutil/trace.go:171","msg":"trace[1647220975] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"241.235311ms","start":"2026-02-17T06:34:34.148388Z","end":"2026-02-17T06:34:34.389623Z","steps":["trace[1647220975] 'agreement among raft nodes before linearized reading' (duration: 241.209401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.389695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.62445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.38971Z","caller":"traceutil/trace.go:171","msg":"trace[1221338242] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"252.649601ms","start":"2026-02-17T06:34:34.137057Z","end":"2026-02-17T06:34:34.389707Z","steps":["trace[1221338242] 'agreement among raft nodes before linearized reading' (duration: 252.627171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.389781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.594703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.389796Z","caller":"traceutil/trace.go:171","msg":"trace[456852420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"253.616853ms","start":"2026-02-17T06:34:34.136176Z","end":"2026-02-17T06:34:34.389793Z","steps":["trace[456852420] 'agreement among raft nodes before linearized reading' (duration: 253.594313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.389847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.685806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.389861Z","caller":"traceutil/trace.go:171","msg":"trace[340790611] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"253.732187ms","start":"2026-02-17T06:34:34.136126Z","end":"2026-02-17T06:34:34.389858Z","steps":["trace[340790611] 'agreement among raft nodes before linearized reading' (duration: 253.710866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.389911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.20171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.389926Z","caller":"traceutil/trace.go:171","msg":"trace[817612734] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"274.223192ms","start":"2026-02-17T06:34:34.115699Z","end":"2026-02-17T06:34:34.389922Z","steps":["trace[817612734] 'agreement among raft nodes before linearized reading' (duration: 274.202311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.389986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.299993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.390002Z","caller":"traceutil/trace.go:171","msg":"trace[1622979925] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"274.352964ms","start":"2026-02-17T06:34:34.115646Z","end":"2026-02-17T06:34:34.389999Z","steps":["trace[1622979925] 'agreement among raft nodes before linearized reading' (duration: 274.331174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.390049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.247536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.390064Z","caller":"traceutil/trace.go:171","msg":"trace[1009506078] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"278.271647ms","start":"2026-02-17T06:34:34.111789Z","end":"2026-02-17T06:34:34.39006Z","steps":["trace[1009506078] 'agreement among raft nodes before linearized reading' (duration: 278.251986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.390119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.674978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.390131Z","caller":"traceutil/trace.go:171","msg":"trace[2014796389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"284.694568ms","start":"2026-02-17T06:34:34.105432Z","end":"2026-02-17T06:34:34.390127Z","steps":["trace[2014796389] 'agreement among raft nodes before linearized reading' (duration: 284.678328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:34.390211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.78646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:34.390224Z","caller":"traceutil/trace.go:171","msg":"trace[1895470262] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"284.801051ms","start":"2026-02-17T06:34:34.105419Z","end":"2026-02-17T06:34:34.39022Z","steps":["trace[1895470262] 'agreement among raft nodes before linearized reading' (duration: 284.77394ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:35.209534Z","caller":"traceutil/trace.go:171","msg":"trace[1837145937] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"100.326402ms","start":"2026-02-17T06:34:35.109174Z","end":"2026-02-17T06:34:35.209501Z","steps":["trace[1837145937] 'process raft request' (duration: 48.042316ms)","trace[1837145937] 'compare' (duration: 52.125332ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:35.438521Z","caller":"traceutil/trace.go:171","msg":"trace[1373455394] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"105.122984ms","start":"2026-02-17T06:34:35.333364Z","end":"2026-02-17T06:34:35.438487Z","steps":["trace[1373455394] 'process raft request' (duration: 103.926056ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:35.441353Z","caller":"traceutil/trace.go:171","msg":"trace[144999875] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"128.900557ms","start":"2026-02-17T06:34:35.312109Z","end":"2026-02-17T06:34:35.44101Z","steps":["trace[144999875] 'process raft request' (duration: 107.330707ms)","trace[144999875] 'compare' (duration: 17.36496ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:35.688994Z","caller":"traceutil/trace.go:171","msg":"trace[2020053102] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"194.253761ms","start":"2026-02-17T06:34:35.494711Z","end":"2026-02-17T06:34:35.688965Z","steps":["trace[2020053102] 'read index received' (duration: 26.748702ms)","trace[2020053102] 'applied index is now lower than readState.Index' (duration: 167.501199ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:35.689761Z","caller":"traceutil/trace.go:171","msg":"trace[1861399791] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"211.825936ms","start":"2026-02-17T06:34:35.477923Z","end":"2026-02-17T06:34:35.689749Z","steps":["trace[1861399791] 'process raft request' (duration: 114.429045ms)","trace[1861399791] 'compare' (duration: 96.354277ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:35.690218Z","caller":"traceutil/trace.go:171","msg":"trace[1503813733] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"192.425547ms","start":"2026-02-17T06:34:35.497754Z","end":"2026-02-17T06:34:35.690179Z","steps":["trace[1503813733] 'process raft request' (duration: 191.101526ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:35.690979Z","caller":"traceutil/trace.go:171","msg":"trace[422852474] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"181.503969ms","start":"2026-02-17T06:34:35.509467Z","end":"2026-02-17T06:34:35.690971Z","steps":["trace[422852474] 'process raft request' (duration: 179.455861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:35.691306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.575685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r4d8k\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-17T06:34:35.691402Z","caller":"traceutil/trace.go:171","msg":"trace[1186385007] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r4d8k; range_end:; response_count:1; response_revision:586; }","duration":"196.715259ms","start":"2026-02-17T06:34:35.494678Z","end":"2026-02-17T06:34:35.691393Z","steps":["trace[1186385007] 'agreement among raft nodes before linearized reading' (duration: 196.484054ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:35.885209Z","caller":"traceutil/trace.go:171","msg":"trace[1499325521] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"133.909375ms","start":"2026-02-17T06:34:35.751269Z","end":"2026-02-17T06:34:35.885179Z","steps":["trace[1499325521] 'process raft request' (duration: 112.818166ms)","trace[1499325521] 'compare' (duration: 20.672049ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:36.021816Z","caller":"traceutil/trace.go:171","msg":"trace[1746779615] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"184.089371ms","start":"2026-02-17T06:34:35.837346Z","end":"2026-02-17T06:34:36.021436Z","steps":["trace[1746779615] 'process raft request' (duration: 182.982465ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.075271Z","caller":"traceutil/trace.go:171","msg":"trace[1291303102] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"172.127417ms","start":"2026-02-17T06:34:35.903114Z","end":"2026-02-17T06:34:36.075242Z","steps":["trace[1291303102] 'process raft request' (duration: 171.676656ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.075493Z","caller":"traceutil/trace.go:171","msg":"trace[560224852] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"184.53213ms","start":"2026-02-17T06:34:35.890949Z","end":"2026-02-17T06:34:36.075481Z","steps":["trace[560224852] 'process raft request' (duration: 183.712421ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.221411Z","caller":"traceutil/trace.go:171","msg":"trace[340899256] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"135.596155ms","start":"2026-02-17T06:34:36.085791Z","end":"2026-02-17T06:34:36.221387Z","steps":["trace[340899256] 'process raft request' (duration: 135.37788ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.221832Z","caller":"traceutil/trace.go:171","msg":"trace[443278714] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"103.048845ms","start":"2026-02-17T06:34:36.118751Z","end":"2026-02-17T06:34:36.2218Z","steps":["trace[443278714] 'process raft request' (duration: 102.942443ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.222058Z","caller":"traceutil/trace.go:171","msg":"trace[683646087] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"116.332519ms","start":"2026-02-17T06:34:36.105709Z","end":"2026-02-17T06:34:36.222042Z","steps":["trace[683646087] 'process raft request' (duration: 115.640463ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.512037Z","caller":"traceutil/trace.go:171","msg":"trace[911925048] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"188.006063ms","start":"2026-02-17T06:34:36.324003Z","end":"2026-02-17T06:34:36.51201Z","steps":["trace[911925048] 'process raft request' (duration: 129.202983ms)","trace[911925048] 'compare' (duration: 58.497113ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:36.521902Z","caller":"traceutil/trace.go:171","msg":"trace[411652426] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"122.595467ms","start":"2026-02-17T06:34:36.39928Z","end":"2026-02-17T06:34:36.521875Z","steps":["trace[411652426] 'read index received' (duration: 53.942474ms)","trace[411652426] 'applied index is now lower than readState.Index' (duration: 68.651833ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:36.522231Z","caller":"traceutil/trace.go:171","msg":"trace[1906242046] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"172.475096ms","start":"2026-02-17T06:34:36.349739Z","end":"2026-02-17T06:34:36.522214Z","steps":["trace[1906242046] 'process raft request' (duration: 171.988675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:36.522773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.475669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:36.524792Z","caller":"traceutil/trace.go:171","msg":"trace[1980240238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"125.550377ms","start":"2026-02-17T06:34:36.399209Z","end":"2026-02-17T06:34:36.52476Z","steps":["trace[1980240238] 'agreement among raft nodes before linearized reading' (duration: 123.336915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:36.565421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.138473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:34:36.565522Z","caller":"traceutil/trace.go:171","msg":"trace[1846508841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"165.260046ms","start":"2026-02-17T06:34:36.400242Z","end":"2026-02-17T06:34:36.565502Z","steps":["trace[1846508841] 'agreement among raft nodes before linearized reading' (duration: 165.076232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:36.56675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.657236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-17T06:34:36.5673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.732584ms","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-17T06:34:36.567537Z","caller":"traceutil/trace.go:171","msg":"trace[2024883311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:605; }","duration":"165.00142ms","start":"2026-02-17T06:34:36.40251Z","end":"2026-02-17T06:34:36.567512Z","steps":["trace[2024883311] 'agreement among raft nodes before linearized reading' (duration: 164.645111ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.5668Z","caller":"traceutil/trace.go:171","msg":"trace[852667729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"157.721388ms","start":"2026-02-17T06:34:36.409066Z","end":"2026-02-17T06:34:36.566788Z","steps":["trace[852667729] 'agreement among raft nodes before linearized reading' (duration: 157.602265ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.643908Z","caller":"traceutil/trace.go:171","msg":"trace[470732863] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"106.549438ms","start":"2026-02-17T06:34:36.537335Z","end":"2026-02-17T06:34:36.643885Z","steps":["trace[470732863] 'process raft request' (duration: 75.931324ms)","trace[470732863] 'compare' (duration: 30.088241ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:36.694191Z","caller":"traceutil/trace.go:171","msg":"trace[272535037] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"104.426518ms","start":"2026-02-17T06:34:36.589737Z","end":"2026-02-17T06:34:36.694163Z","steps":["trace[272535037] 'process raft request' (duration: 104.343936ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.694208Z","caller":"traceutil/trace.go:171","msg":"trace[278231477] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"118.627634ms","start":"2026-02-17T06:34:36.575534Z","end":"2026-02-17T06:34:36.694162Z","steps":["trace[278231477] 'process raft request' (duration: 118.336507ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.694439Z","caller":"traceutil/trace.go:171","msg":"trace[1744561804] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"112.55481ms","start":"2026-02-17T06:34:36.581875Z","end":"2026-02-17T06:34:36.694429Z","steps":["trace[1744561804] 'process raft request' (duration: 112.155371ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:36.694492Z","caller":"traceutil/trace.go:171","msg":"trace[734901089] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"112.420776ms","start":"2026-02-17T06:34:36.582066Z","end":"2026-02-17T06:34:36.694487Z","steps":["trace[734901089] 'process raft request' (duration: 111.990176ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:37.193711Z","caller":"traceutil/trace.go:171","msg":"trace[334479464] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"176.484481ms","start":"2026-02-17T06:34:37.017204Z","end":"2026-02-17T06:34:37.193689Z","steps":["trace[334479464] 'process raft request' (duration: 174.475984ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:34:38.709962Z","caller":"traceutil/trace.go:171","msg":"trace[1136078745] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"205.440785ms","start":"2026-02-17T06:34:38.504461Z","end":"2026-02-17T06:34:38.709902Z","steps":["trace[1136078745] 'read index received' (duration: 200.355115ms)","trace[1136078745] 'applied index is now lower than readState.Index' (duration: 5.08475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:38.711077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.600632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-17T06:34:38.717206Z","caller":"traceutil/trace.go:171","msg":"trace[282963346] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"212.778379ms","start":"2026-02-17T06:34:38.504398Z","end":"2026-02-17T06:34:38.717176Z","steps":["trace[282963346] 'agreement among raft nodes before linearized reading' (duration: 205.592459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:38.717273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.188328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r4d8k\" ","response":"range_response_count:1 size:18075"} {"level":"info","ts":"2026-02-17T06:34:38.717287Z","caller":"traceutil/trace.go:171","msg":"trace[76697773] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r4d8k; range_end:; response_count:1; response_revision:622; }","duration":"188.234738ms","start":"2026-02-17T06:34:38.529048Z","end":"2026-02-17T06:34:38.717283Z","steps":["trace[76697773] 'agreement among raft nodes before linearized reading' (duration: 182.089403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:43.889944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.220323ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-17T06:34:43.890082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:43.223381Z","time spent":"666.697318ms","remote":"127.0.0.1:58134","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T06:34:45.221314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.238369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:34:45.221366Z","caller":"traceutil/trace.go:171","msg":"trace[402859621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"468.771296ms","start":"2026-02-17T06:34:44.752582Z","end":"2026-02-17T06:34:45.221354Z","steps":["trace[402859621] 'range keys from in-memory index tree' (duration: 467.125396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:45.221391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:44.75257Z","time spent":"468.816396ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T06:34:45.221542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.625571ms","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-17T06:34:45.221559Z","caller":"traceutil/trace.go:171","msg":"trace[1580341340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"451.662841ms","start":"2026-02-17T06:34:44.76989Z","end":"2026-02-17T06:34:45.221553Z","steps":["trace[1580341340] 'range keys from in-memory index tree' (duration: 450.394211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:45.221574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:44.769878Z","time spent":"451.693103ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:45.22172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.421449ms","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-17T06:34:45.221738Z","caller":"traceutil/trace.go:171","msg":"trace[729922157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:667; }","duration":"327.461661ms","start":"2026-02-17T06:34:44.89427Z","end":"2026-02-17T06:34:45.221732Z","steps":["trace[729922157] 'range keys from in-memory index tree' (duration: 327.344428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:45.221753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:44.894257Z","time spent":"327.492521ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:45.221849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.400698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:45.221866Z","caller":"traceutil/trace.go:171","msg":"trace[138908986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"327.42385ms","start":"2026-02-17T06:34:44.894436Z","end":"2026-02-17T06:34:45.22186Z","steps":["trace[138908986] 'range keys from in-memory index tree' (duration: 327.362288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:45.221886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:44.894433Z","time spent":"327.44685ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:47.888008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333076644,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T06:34:47.940663Z","caller":"traceutil/trace.go:171","msg":"trace[559135984] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"567.2862ms","start":"2026-02-17T06:34:47.373363Z","end":"2026-02-17T06:34:47.940649Z","steps":["trace[559135984] 'process raft request' (duration: 563.956441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.940782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.373344Z","time spent":"567.375223ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:47.943024Z","caller":"traceutil/trace.go:171","msg":"trace[618009930] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:703; }","duration":"555.978653ms","start":"2026-02-17T06:34:47.387034Z","end":"2026-02-17T06:34:47.943012Z","steps":["trace[618009930] 'read index received' (duration: 550.292929ms)","trace[618009930] 'applied index is now lower than readState.Index' (duration: 5.685074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:47.943216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.167578ms","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-17T06:34:47.943242Z","caller":"traceutil/trace.go:171","msg":"trace[1587777597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"556.204179ms","start":"2026-02-17T06:34:47.387029Z","end":"2026-02-17T06:34:47.943233Z","steps":["trace[1587777597] 'agreement among raft nodes before linearized reading' (duration: 556.057856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.943265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.386986Z","time spent":"556.274161ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T06:34:47.943397Z","caller":"traceutil/trace.go:171","msg":"trace[1425135584] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"506.358582ms","start":"2026-02-17T06:34:47.437031Z","end":"2026-02-17T06:34:47.94339Z","steps":["trace[1425135584] 'process raft request' (duration: 505.83478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.943438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.437016Z","time spent":"506.397354ms","remote":"127.0.0.1:58166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T06:34:47.94369Z","caller":"traceutil/trace.go:171","msg":"trace[233652083] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"500.31635ms","start":"2026-02-17T06:34:47.443366Z","end":"2026-02-17T06:34:47.943683Z","steps":["trace[233652083] 'process raft request' (duration: 499.565062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.943758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.443351Z","time spent":"500.361961ms","remote":"127.0.0.1:58252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4975,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:47.943903Z","caller":"traceutil/trace.go:171","msg":"trace[41567823] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"378.537775ms","start":"2026-02-17T06:34:47.565359Z","end":"2026-02-17T06:34:47.943897Z","steps":["trace[41567823] 'process raft request' (duration: 377.627793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.943939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.56534Z","time spent":"378.579677ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:47.958098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.212814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:47.958164Z","caller":"traceutil/trace.go:171","msg":"trace[2004430777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"510.333327ms","start":"2026-02-17T06:34:47.447809Z","end":"2026-02-17T06:34:47.958142Z","steps":["trace[2004430777] 'agreement among raft nodes before linearized reading' (duration: 510.199873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.958182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.447796Z","time spent":"510.381887ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:34:47.958346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.231844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:34:47.95836Z","caller":"traceutil/trace.go:171","msg":"trace[1648496356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"571.251405ms","start":"2026-02-17T06:34:47.387105Z","end":"2026-02-17T06:34:47.958356Z","steps":["trace[1648496356] 'agreement among raft nodes before linearized reading' (duration: 571.184593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.958374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.387101Z","time spent":"571.268085ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T06:34:47.958481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.380458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:47.958494Z","caller":"traceutil/trace.go:171","msg":"trace[350354795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"571.401598ms","start":"2026-02-17T06:34:47.387089Z","end":"2026-02-17T06:34:47.95849Z","steps":["trace[350354795] 'agreement among raft nodes before linearized reading' (duration: 571.348037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.958506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.387086Z","time spent":"571.417048ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:47.958654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.568842ms","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-17T06:34:47.958681Z","caller":"traceutil/trace.go:171","msg":"trace[1022123432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"571.591503ms","start":"2026-02-17T06:34:47.387072Z","end":"2026-02-17T06:34:47.958663Z","steps":["trace[1022123432] 'agreement among raft nodes before linearized reading' (duration: 571.553472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:47.958718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:47.387069Z","time spent":"571.624384ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T06:34:52.383912Z","caller":"traceutil/trace.go:171","msg":"trace[897303641] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"405.881622ms","start":"2026-02-17T06:34:51.977993Z","end":"2026-02-17T06:34:52.383875Z","steps":["trace[897303641] 'read index received' (duration: 405.643727ms)","trace[897303641] 'applied index is now lower than readState.Index' (duration: 237.145µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:52.384181Z","caller":"traceutil/trace.go:171","msg":"trace[1203333202] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"416.473852ms","start":"2026-02-17T06:34:51.967696Z","end":"2026-02-17T06:34:52.38417Z","steps":["trace[1203333202] 'process raft request' (duration: 416.029732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:52.384289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:51.967685Z","time spent":"416.521664ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:52.384616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.6205ms","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-17T06:34:52.384647Z","caller":"traceutil/trace.go:171","msg":"trace[921940090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"406.672051ms","start":"2026-02-17T06:34:51.977966Z","end":"2026-02-17T06:34:52.384638Z","steps":["trace[921940090] 'agreement among raft nodes before linearized reading' (duration: 406.507228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:52.38469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:51.977958Z","time spent":"406.706632ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:52.384965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.415952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:52.385013Z","caller":"traceutil/trace.go:171","msg":"trace[24766403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"397.452464ms","start":"2026-02-17T06:34:51.98753Z","end":"2026-02-17T06:34:52.384982Z","steps":["trace[24766403] 'agreement among raft nodes before linearized reading' (duration: 397.27964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:52.385037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:51.987523Z","time spent":"397.508205ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:52.385124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.33689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:52.385166Z","caller":"traceutil/trace.go:171","msg":"trace[1893285107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"174.413852ms","start":"2026-02-17T06:34:52.210743Z","end":"2026-02-17T06:34:52.385157Z","steps":["trace[1893285107] 'agreement among raft nodes before linearized reading' (duration: 174.36246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:52.385351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.523717ms","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-17T06:34:52.385392Z","caller":"traceutil/trace.go:171","msg":"trace[1165209927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"392.590788ms","start":"2026-02-17T06:34:51.992794Z","end":"2026-02-17T06:34:52.385385Z","steps":["trace[1165209927] 'agreement among raft nodes before linearized reading' (duration: 392.487936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:52.385413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:51.992763Z","time spent":"392.64529ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T06:34:52.38554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.121862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:34:52.38556Z","caller":"traceutil/trace.go:171","msg":"trace[869651214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"396.171883ms","start":"2026-02-17T06:34:51.989382Z","end":"2026-02-17T06:34:52.385554Z","steps":["trace[869651214] 'agreement among raft nodes before linearized reading' (duration: 396.094881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:52.385582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:51.989373Z","time spent":"396.203494ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T06:34:53.090728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333076718,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:34:53.537389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.739594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:34:53.537639Z","caller":"traceutil/trace.go:171","msg":"trace[2136526617] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.143670259s","start":"2026-02-17T06:34:52.39394Z","end":"2026-02-17T06:34:53.537611Z","steps":["trace[2136526617] 'process raft request' (duration: 873.583036ms)","trace[2136526617] 'compare' (duration: 269.53129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:34:53.537718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.393923Z","time spent":"1.14375662s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:53.950911Z","caller":"traceutil/trace.go:171","msg":"trace[1675012689] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:751; }","duration":"1.36083193s","start":"2026-02-17T06:34:52.590054Z","end":"2026-02-17T06:34:53.950886Z","steps":["trace[1675012689] 'read index received' (duration: 677.478561ms)","trace[1675012689] 'applied index is now lower than readState.Index' (duration: 683.352379ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:34:53.952402Z","caller":"traceutil/trace.go:171","msg":"trace[289581189] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.554447316s","start":"2026-02-17T06:34:52.397943Z","end":"2026-02-17T06:34:53.95239Z","steps":["trace[289581189] 'process raft request' (duration: 1.549658163s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.952868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.39792Z","time spent":"1.554725913s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:53.953295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363192566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:53.954596Z","caller":"traceutil/trace.go:171","msg":"trace[1644888734] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:730; }","duration":"1.364557999s","start":"2026-02-17T06:34:52.590022Z","end":"2026-02-17T06:34:53.95458Z","steps":["trace[1644888734] 'agreement among raft nodes before linearized reading' (duration: 1.363160336s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.954635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.590006Z","time spent":"1.36461569s","remote":"127.0.0.1:58218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-17T06:34:53.953393Z","caller":"traceutil/trace.go:171","msg":"trace[140722700] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.554368834s","start":"2026-02-17T06:34:52.399001Z","end":"2026-02-17T06:34:53.953369Z","steps":["trace[140722700] 'process raft request' (duration: 1.550536873s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.954817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.398994Z","time spent":"1.555795638s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:53.953432Z","caller":"traceutil/trace.go:171","msg":"trace[1420426744] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.552270254s","start":"2026-02-17T06:34:52.401154Z","end":"2026-02-17T06:34:53.953424Z","steps":["trace[1420426744] 'process raft request' (duration: 1.548455854s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.954943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.401144Z","time spent":"1.55377994s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:34:53.953462Z","caller":"traceutil/trace.go:171","msg":"trace[824168998] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"879.685609ms","start":"2026-02-17T06:34:53.073768Z","end":"2026-02-17T06:34:53.953453Z","steps":["trace[824168998] 'process raft request' (duration: 877.060907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.95507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:53.07374Z","time spent":"881.309148ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:34:53.953546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110725319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:53.955209Z","caller":"traceutil/trace.go:171","msg":"trace[658257482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"1.112412539s","start":"2026-02-17T06:34:52.84279Z","end":"2026-02-17T06:34:53.955202Z","steps":["trace[658257482] 'agreement among raft nodes before linearized reading' (duration: 1.1107372s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.95523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.842776Z","time spent":"1.11244747s","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:34:53.95368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.756103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T06:34:53.959994Z","caller":"traceutil/trace.go:171","msg":"trace[173285637] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:730; }","duration":"738.087122ms","start":"2026-02-17T06:34:53.221892Z","end":"2026-02-17T06:34:53.959979Z","steps":["trace[173285637] 'agreement among raft nodes before linearized reading' (duration: 731.669112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.96003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:53.221878Z","time spent":"738.140013ms","remote":"127.0.0.1:58134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-17T06:34:53.953719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038731538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:34:53.960186Z","caller":"traceutil/trace.go:171","msg":"trace[544796501] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:730; }","duration":"1.04522003s","start":"2026-02-17T06:34:52.914957Z","end":"2026-02-17T06:34:53.960177Z","steps":["trace[544796501] 'agreement among raft nodes before linearized reading' (duration: 1.038744168s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:53.96021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:34:52.914944Z","time spent":"1.045257592s","remote":"127.0.0.1:58360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-17T06:34:56.130993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.314522ms","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-17T06:34:56.131054Z","caller":"traceutil/trace.go:171","msg":"trace[480502852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"150.425894ms","start":"2026-02-17T06:34:55.980613Z","end":"2026-02-17T06:34:56.131039Z","steps":["trace[480502852] 'range keys from in-memory index tree' (duration: 150.146478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:56.131201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.357043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:34:56.131215Z","caller":"traceutil/trace.go:171","msg":"trace[845250755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"150.405844ms","start":"2026-02-17T06:34:55.980803Z","end":"2026-02-17T06:34:56.131209Z","steps":["trace[845250755] 'range keys from in-memory index tree' (duration: 150.288211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:34:56.131303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.119439ms","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-17T06:34:56.13132Z","caller":"traceutil/trace.go:171","msg":"trace[2126482566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"142.1616ms","start":"2026-02-17T06:34:55.989154Z","end":"2026-02-17T06:34:56.131315Z","steps":["trace[2126482566] 'range keys from in-memory index tree' (duration: 142.066098ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:12.254835Z","caller":"traceutil/trace.go:171","msg":"trace[1257575648] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"553.866579ms","start":"2026-02-17T06:35:11.700946Z","end":"2026-02-17T06:35:12.254812Z","steps":["trace[1257575648] 'process raft request' (duration: 553.674574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:12.254979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:11.700934Z","time spent":"553.973751ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:13.071159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333076894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:35:13.358457Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095225753s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T06:35:13.359314Z","caller":"traceutil/trace.go:171","msg":"trace[1800155448] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.096139815s","start":"2026-02-17T06:35:12.263153Z","end":"2026-02-17T06:35:13.359293Z","steps":["trace[1800155448] 'process raft request' (duration: 1.095962051s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:13.359635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:12.263143Z","time spent":"1.096393211s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:13.360213Z","caller":"traceutil/trace.go:171","msg":"trace[1905487338] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:814; }","duration":"789.927268ms","start":"2026-02-17T06:35:12.570269Z","end":"2026-02-17T06:35:13.360197Z","steps":["trace[1905487338] 'read index received' (duration: 788.774871ms)","trace[1905487338] 'applied index is now lower than readState.Index' (duration: 1.151167ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:13.360328Z","caller":"traceutil/trace.go:171","msg":"trace[173079785] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"1.092653812s","start":"2026-02-17T06:35:12.267664Z","end":"2026-02-17T06:35:13.360317Z","steps":["trace[173079785] 'process raft request' (duration: 1.092477598s)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:13.360308Z","caller":"traceutil/trace.go:171","msg":"trace[1176276136] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.09509784s","start":"2026-02-17T06:35:12.265166Z","end":"2026-02-17T06:35:13.360264Z","steps":["trace[1176276136] 'process raft request' (duration: 1.094903985s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:13.360388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:12.267656Z","time spent":"1.092693154s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:13.361039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:12.265159Z","time spent":"1.095243034s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:13.361673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.024667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:13.361715Z","caller":"traceutil/trace.go:171","msg":"trace[544216583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"522.110079ms","start":"2026-02-17T06:35:12.83959Z","end":"2026-02-17T06:35:13.3617Z","steps":["trace[544216583] 'agreement among raft nodes before linearized reading' (duration: 522.045847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:13.361744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:12.839565Z","time spent":"522.173341ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:35:13.361982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.128328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T06:35:13.362025Z","caller":"traceutil/trace.go:171","msg":"trace[1021966771] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:786; }","duration":"139.20504ms","start":"2026-02-17T06:35:13.222812Z","end":"2026-02-17T06:35:13.362017Z","steps":["trace[1021966771] 'agreement among raft nodes before linearized reading' (duration: 139.059017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:13.366345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.067214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:35:13.368068Z","caller":"traceutil/trace.go:171","msg":"trace[202525475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"797.830296ms","start":"2026-02-17T06:35:12.570221Z","end":"2026-02-17T06:35:13.368051Z","steps":["trace[202525475] 'agreement among raft nodes before linearized reading' (duration: 790.297408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:13.368139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:12.570198Z","time spent":"797.922907ms","remote":"127.0.0.1:58322","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":"info","ts":"2026-02-17T06:35:16.404311Z","caller":"traceutil/trace.go:171","msg":"trace[920124181] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"100.565517ms","start":"2026-02-17T06:35:16.303729Z","end":"2026-02-17T06:35:16.404294Z","steps":["trace[920124181] 'process raft request' (duration: 100.462824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:17.136022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.909507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:17.136115Z","caller":"traceutil/trace.go:171","msg":"trace[1052981154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"297.03975ms","start":"2026-02-17T06:35:16.839056Z","end":"2026-02-17T06:35:17.136096Z","steps":["trace[1052981154] 'range keys from in-memory index tree' (duration: 296.849956ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:17.776388Z","caller":"traceutil/trace.go:171","msg":"trace[1478662109] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"344.012551ms","start":"2026-02-17T06:35:17.432357Z","end":"2026-02-17T06:35:17.776369Z","steps":["trace[1478662109] 'read index received' (duration: 343.865028ms)","trace[1478662109] 'applied index is now lower than readState.Index' (duration: 146.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:17.776632Z","caller":"traceutil/trace.go:171","msg":"trace[95386977] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"368.915979ms","start":"2026-02-17T06:35:17.407708Z","end":"2026-02-17T06:35:17.776624Z","steps":["trace[95386977] 'process raft request' (duration: 368.560741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:17.776714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:17.407678Z","time spent":"368.975721ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:17.776894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.536303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:35:17.776917Z","caller":"traceutil/trace.go:171","msg":"trace[198923524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"344.581214ms","start":"2026-02-17T06:35:17.432329Z","end":"2026-02-17T06:35:17.77691Z","steps":["trace[198923524] 'agreement among raft nodes before linearized reading' (duration: 344.499232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:17.776935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:17.432315Z","time spent":"344.616585ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:17.77704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.332028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:35:17.777055Z","caller":"traceutil/trace.go:171","msg":"trace[399264523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"344.39913ms","start":"2026-02-17T06:35:17.432651Z","end":"2026-02-17T06:35:17.77705Z","steps":["trace[399264523] 'agreement among raft nodes before linearized reading' (duration: 344.352389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:17.77707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:17.432631Z","time spent":"344.435811ms","remote":"127.0.0.1:58322","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-17T06:35:17.777245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.490499ms","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-17T06:35:17.777269Z","caller":"traceutil/trace.go:171","msg":"trace[1585387370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"343.55106ms","start":"2026-02-17T06:35:17.433711Z","end":"2026-02-17T06:35:17.777262Z","steps":["trace[1585387370] 'agreement among raft nodes before linearized reading' (duration: 343.485179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:17.777288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:17.433695Z","time spent":"343.586921ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T06:35:17.923845Z","caller":"traceutil/trace.go:171","msg":"trace[972431596] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"140.183252ms","start":"2026-02-17T06:35:17.783637Z","end":"2026-02-17T06:35:17.92382Z","steps":["trace[972431596] 'process raft request' (duration: 56.124936ms)","trace[972431596] 'compare' (duration: 81.67435ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:17.924247Z","caller":"traceutil/trace.go:171","msg":"trace[1414682288] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"140.413528ms","start":"2026-02-17T06:35:17.78382Z","end":"2026-02-17T06:35:17.924233Z","steps":["trace[1414682288] 'process raft request' (duration: 137.815067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.779764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.123271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:35:24.779896Z","caller":"traceutil/trace.go:171","msg":"trace[1510666133] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"974.006739ms","start":"2026-02-17T06:35:23.805867Z","end":"2026-02-17T06:35:24.779873Z","steps":["trace[1510666133] 'read index received' (duration: 46.612222ms)","trace[1510666133] 'applied index is now lower than readState.Index' (duration: 927.393217ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:24.779994Z","caller":"traceutil/trace.go:171","msg":"trace[838616729] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.306983039s","start":"2026-02-17T06:35:23.472998Z","end":"2026-02-17T06:35:24.779981Z","steps":["trace[838616729] 'process raft request' (duration: 379.51422ms)","trace[838616729] 'compare' (duration: 926.870685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:24.780056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:23.472978Z","time spent":"1.30704273s","remote":"127.0.0.1:58134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:24.780472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.609373ms","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-17T06:35:24.780514Z","caller":"traceutil/trace.go:171","msg":"trace[2055057354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"974.685255ms","start":"2026-02-17T06:35:23.805808Z","end":"2026-02-17T06:35:24.780493Z","steps":["trace[2055057354] 'agreement among raft nodes before linearized reading' (duration: 974.48166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.780543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:23.805781Z","time spent":"974.755267ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T06:35:24.787538Z","caller":"traceutil/trace.go:171","msg":"trace[313467627] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"380.978854ms","start":"2026-02-17T06:35:24.406548Z","end":"2026-02-17T06:35:24.787527Z","steps":["trace[313467627] 'process raft request' (duration: 380.734118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.788357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.406508Z","time spent":"381.796693ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:24.788874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.857037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-17T06:35:24.789062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.917402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:35:24.789148Z","caller":"traceutil/trace.go:171","msg":"trace[1921389438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"812.979933ms","start":"2026-02-17T06:35:23.976102Z","end":"2026-02-17T06:35:24.789081Z","steps":["trace[1921389438] 'agreement among raft nodes before linearized reading' (duration: 812.88641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.789242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:23.976081Z","time spent":"813.151877ms","remote":"127.0.0.1:58322","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-17T06:35:24.789445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.548258ms","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-17T06:35:24.78947Z","caller":"traceutil/trace.go:171","msg":"trace[138386459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"805.595879ms","start":"2026-02-17T06:35:23.983867Z","end":"2026-02-17T06:35:24.789463Z","steps":["trace[138386459] 'agreement among raft nodes before linearized reading' (duration: 805.528547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.78961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:23.983856Z","time spent":"805.689591ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:24.78997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.236107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:35:24.790116Z","caller":"traceutil/trace.go:171","msg":"trace[336210948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"920.433493ms","start":"2026-02-17T06:35:23.869675Z","end":"2026-02-17T06:35:24.790109Z","steps":["trace[336210948] 'agreement among raft nodes before linearized reading' (duration: 920.246848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.790246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:23.869648Z","time spent":"920.542305ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:24.790563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.284491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:24.790653Z","caller":"traceutil/trace.go:171","msg":"trace[1809077838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"951.342473ms","start":"2026-02-17T06:35:23.839239Z","end":"2026-02-17T06:35:24.790582Z","steps":["trace[1809077838] 'agreement among raft nodes before linearized reading' (duration: 951.300822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.790725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:23.839221Z","time spent":"951.497026ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T06:35:24.791014Z","caller":"traceutil/trace.go:171","msg":"trace[1672533029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"313.942939ms","start":"2026-02-17T06:35:24.474956Z","end":"2026-02-17T06:35:24.788899Z","steps":["trace[1672533029] 'agreement among raft nodes before linearized reading' (duration: 313.824666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:24.791123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.474937Z","time spent":"316.099781ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T06:35:25.09702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.173273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:35:25.097353Z","caller":"traceutil/trace.go:171","msg":"trace[1861242688] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"301.990577ms","start":"2026-02-17T06:35:24.795341Z","end":"2026-02-17T06:35:25.097332Z","steps":["trace[1861242688] 'process raft request' (duration: 301.945956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:25.097436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.795336Z","time spent":"302.066148ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:25.097877Z","caller":"traceutil/trace.go:171","msg":"trace[671586735] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"309.909834ms","start":"2026-02-17T06:35:24.787953Z","end":"2026-02-17T06:35:25.097863Z","steps":["trace[671586735] 'process raft request' (duration: 154.775808ms)","trace[671586735] 'compare' (duration: 154.02755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:25.098047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.787938Z","time spent":"309.974455ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:25.098268Z","caller":"traceutil/trace.go:171","msg":"trace[143308543] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"309.487215ms","start":"2026-02-17T06:35:24.788768Z","end":"2026-02-17T06:35:25.098255Z","steps":["trace[143308543] 'read index received' (duration: 153.971899ms)","trace[143308543] 'applied index is now lower than readState.Index' (duration: 155.513475ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:25.098382Z","caller":"traceutil/trace.go:171","msg":"trace[1704443129] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"305.249674ms","start":"2026-02-17T06:35:24.793106Z","end":"2026-02-17T06:35:25.098356Z","steps":["trace[1704443129] 'process raft request' (duration: 304.056186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:25.098837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.7931Z","time spent":"305.677885ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:25.099032Z","caller":"traceutil/trace.go:171","msg":"trace[1766282343] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"304.916937ms","start":"2026-02-17T06:35:24.794102Z","end":"2026-02-17T06:35:25.099018Z","steps":["trace[1766282343] 'process raft request' (duration: 303.109534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:25.09952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.794093Z","time spent":"305.371117ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:25.099734Z","caller":"traceutil/trace.go:171","msg":"trace[1710855774] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"304.506896ms","start":"2026-02-17T06:35:24.795174Z","end":"2026-02-17T06:35:25.099681Z","steps":["trace[1710855774] 'process raft request' (duration: 302.078689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:25.099805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.795168Z","time spent":"304.602298ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:25.100214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.680634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-17T06:35:25.100259Z","caller":"traceutil/trace.go:171","msg":"trace[770064128] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:818; }","duration":"316.747216ms","start":"2026-02-17T06:35:24.783499Z","end":"2026-02-17T06:35:25.100247Z","steps":["trace[770064128] 'agreement among raft nodes before linearized reading' (duration: 316.617282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:25.100306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.783481Z","time spent":"316.810568ms","remote":"127.0.0.1:58234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-17T06:35:25.100496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.442946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:25.100528Z","caller":"traceutil/trace.go:171","msg":"trace[1784219996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"307.473897ms","start":"2026-02-17T06:35:24.793046Z","end":"2026-02-17T06:35:25.100519Z","steps":["trace[1784219996] 'agreement among raft nodes before linearized reading' (duration: 307.424616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:25.10056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:24.793025Z","time spent":"307.528078ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:35:39.249755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.014791ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T06:35:39.467037Z","caller":"traceutil/trace.go:171","msg":"trace[2024145916] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"213.281694ms","start":"2026-02-17T06:35:39.253736Z","end":"2026-02-17T06:35:39.467018Z","steps":["trace[2024145916] 'process raft request' (duration: 213.1306ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:39.482925Z","caller":"traceutil/trace.go:171","msg":"trace[130729566] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"175.041896ms","start":"2026-02-17T06:35:39.307866Z","end":"2026-02-17T06:35:39.482908Z","steps":["trace[130729566] 'read index received' (duration: 159.146474ms)","trace[130729566] 'applied index is now lower than readState.Index' (duration: 15.894762ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:39.483028Z","caller":"traceutil/trace.go:171","msg":"trace[820124141] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"228.850108ms","start":"2026-02-17T06:35:39.254171Z","end":"2026-02-17T06:35:39.483021Z","steps":["trace[820124141] 'process raft request' (duration: 228.626543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:39.483193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.288912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T06:35:39.483219Z","caller":"traceutil/trace.go:171","msg":"trace[484575046] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:905; }","duration":"175.378754ms","start":"2026-02-17T06:35:39.307829Z","end":"2026-02-17T06:35:39.483208Z","steps":["trace[484575046] 'agreement among raft nodes before linearized reading' (duration: 175.299642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:39.483436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.086319ms","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-17T06:35:39.48345Z","caller":"traceutil/trace.go:171","msg":"trace[188990657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"131.138191ms","start":"2026-02-17T06:35:39.352307Z","end":"2026-02-17T06:35:39.483445Z","steps":["trace[188990657] 'agreement among raft nodes before linearized reading' (duration: 131.037318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:39.483585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.721552ms","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-17T06:35:39.483598Z","caller":"traceutil/trace.go:171","msg":"trace[1151415303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"118.759833ms","start":"2026-02-17T06:35:39.364835Z","end":"2026-02-17T06:35:39.483595Z","steps":["trace[1151415303] 'agreement among raft nodes before linearized reading' (duration: 118.689162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:41.987614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333077192,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T06:35:42.450171Z","caller":"traceutil/trace.go:171","msg":"trace[1521850242] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"962.645367ms","start":"2026-02-17T06:35:41.487503Z","end":"2026-02-17T06:35:42.450149Z","steps":["trace[1521850242] 'read index received' (duration: 962.453963ms)","trace[1521850242] 'applied index is now lower than readState.Index' (duration: 190.464µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:42.450446Z","caller":"traceutil/trace.go:171","msg":"trace[831950998] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"977.362871ms","start":"2026-02-17T06:35:41.473075Z","end":"2026-02-17T06:35:42.450438Z","steps":["trace[831950998] 'process raft request' (duration: 976.948141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:42.45055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:41.473061Z","time spent":"977.411722ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:42.450769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.264512ms","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-17T06:35:42.450792Z","caller":"traceutil/trace.go:171","msg":"trace[340901573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"963.318164ms","start":"2026-02-17T06:35:41.487467Z","end":"2026-02-17T06:35:42.450785Z","steps":["trace[340901573] 'agreement among raft nodes before linearized reading' (duration: 963.215551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:42.450811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:41.487444Z","time spent":"963.362845ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T06:35:42.450936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.69318ms","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-17T06:35:42.450955Z","caller":"traceutil/trace.go:171","msg":"trace[1895233227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"935.710901ms","start":"2026-02-17T06:35:41.515237Z","end":"2026-02-17T06:35:42.450948Z","steps":["trace[1895233227] 'agreement among raft nodes before linearized reading' (duration: 935.654959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:42.450972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:41.515212Z","time spent":"935.755661ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:42.4511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.724605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:42.451117Z","caller":"traceutil/trace.go:171","msg":"trace[978456708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"243.773476ms","start":"2026-02-17T06:35:42.207338Z","end":"2026-02-17T06:35:42.451111Z","steps":["trace[978456708] 'agreement among raft nodes before linearized reading' (duration: 243.747756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:42.451261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.004234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:35:42.45128Z","caller":"traceutil/trace.go:171","msg":"trace[2143019116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"935.033034ms","start":"2026-02-17T06:35:41.516241Z","end":"2026-02-17T06:35:42.451274Z","steps":["trace[2143019116] 'agreement among raft nodes before linearized reading' (duration: 934.978833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:42.451296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:41.516237Z","time spent":"935.055015ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:42.451398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.188458ms","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-17T06:35:42.451417Z","caller":"traceutil/trace.go:171","msg":"trace[1901346091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"935.231369ms","start":"2026-02-17T06:35:41.516181Z","end":"2026-02-17T06:35:42.451412Z","steps":["trace[1901346091] 'agreement among raft nodes before linearized reading' (duration: 935.180247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:42.451433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:41.516176Z","time spent":"935.252839ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T06:35:42.585179Z","caller":"traceutil/trace.go:171","msg":"trace[1789498477] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"113.417415ms","start":"2026-02-17T06:35:42.471743Z","end":"2026-02-17T06:35:42.58516Z","steps":["trace[1789498477] 'process raft request' (duration: 113.127088ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:42.585914Z","caller":"traceutil/trace.go:171","msg":"trace[1853346105] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"114.133772ms","start":"2026-02-17T06:35:42.471773Z","end":"2026-02-17T06:35:42.585907Z","steps":["trace[1853346105] 'process raft request' (duration: 113.197979ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:42.586108Z","caller":"traceutil/trace.go:171","msg":"trace[1106486196] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"112.496763ms","start":"2026-02-17T06:35:42.473606Z","end":"2026-02-17T06:35:42.586102Z","steps":["trace[1106486196] 'process raft request' (duration: 111.383016ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:44.648228Z","caller":"traceutil/trace.go:171","msg":"trace[1951934985] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"143.329984ms","start":"2026-02-17T06:35:44.504871Z","end":"2026-02-17T06:35:44.648201Z","steps":["trace[1951934985] 'process raft request' (duration: 142.865073ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:44.800775Z","caller":"traceutil/trace.go:171","msg":"trace[451642270] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"146.615353ms","start":"2026-02-17T06:35:44.654134Z","end":"2026-02-17T06:35:44.80075Z","steps":["trace[451642270] 'process raft request' (duration: 142.396852ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:44.877581Z","caller":"traceutil/trace.go:171","msg":"trace[2087565945] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"220.120218ms","start":"2026-02-17T06:35:44.657439Z","end":"2026-02-17T06:35:44.87756Z","steps":["trace[2087565945] 'process raft request' (duration: 220.088357ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:44.877952Z","caller":"traceutil/trace.go:171","msg":"trace[995231749] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"223.454269ms","start":"2026-02-17T06:35:44.654493Z","end":"2026-02-17T06:35:44.877947Z","steps":["trace[995231749] 'process raft request' (duration: 222.889115ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:44.878013Z","caller":"traceutil/trace.go:171","msg":"trace[453646192] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"222.019934ms","start":"2026-02-17T06:35:44.655988Z","end":"2026-02-17T06:35:44.878008Z","steps":["trace[453646192] 'process raft request' (duration: 221.507282ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:45.293465Z","caller":"traceutil/trace.go:171","msg":"trace[384321429] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"195.151069ms","start":"2026-02-17T06:35:45.098292Z","end":"2026-02-17T06:35:45.293443Z","steps":["trace[384321429] 'process raft request' (duration: 194.996825ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:35:54.820399Z","caller":"traceutil/trace.go:171","msg":"trace[2109272142] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"114.535931ms","start":"2026-02-17T06:35:54.705847Z","end":"2026-02-17T06:35:54.820383Z","steps":["trace[2109272142] 'process raft request' (duration: 114.421068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:55.178529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.31754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:35:55.178599Z","caller":"traceutil/trace.go:171","msg":"trace[835170776] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"221.180714ms","start":"2026-02-17T06:35:54.957406Z","end":"2026-02-17T06:35:55.178587Z","steps":["trace[835170776] 'read index received' (duration: 78.66931ms)","trace[835170776] 'applied index is now lower than readState.Index' (duration: 142.510454ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:55.178751Z","caller":"traceutil/trace.go:171","msg":"trace[1742273325] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"324.21892ms","start":"2026-02-17T06:35:54.854525Z","end":"2026-02-17T06:35:55.178744Z","steps":["trace[1742273325] 'process raft request' (duration: 181.616723ms)","trace[1742273325] 'compare' (duration: 142.068474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:55.178786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:54.854513Z","time spent":"324.256221ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:55.17893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.523162ms","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-17T06:35:55.178943Z","caller":"traceutil/trace.go:171","msg":"trace[426104447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"221.557064ms","start":"2026-02-17T06:35:54.957382Z","end":"2026-02-17T06:35:55.178939Z","steps":["trace[426104447] 'agreement among raft nodes before linearized reading' (duration: 221.493302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:55.179036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.866008ms","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-17T06:35:55.179047Z","caller":"traceutil/trace.go:171","msg":"trace[1005788862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"163.900818ms","start":"2026-02-17T06:35:55.015143Z","end":"2026-02-17T06:35:55.179043Z","steps":["trace[1005788862] 'agreement among raft nodes before linearized reading' (duration: 163.863827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:55.179164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.323375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:35:55.179177Z","caller":"traceutil/trace.go:171","msg":"trace[2059492018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"163.347885ms","start":"2026-02-17T06:35:55.015825Z","end":"2026-02-17T06:35:55.179173Z","steps":["trace[2059492018] 'agreement among raft nodes before linearized reading' (duration: 163.298114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:56.286194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333077294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:35:56.44622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1005632s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:35:56.446359Z","caller":"traceutil/trace.go:171","msg":"trace[779953194] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:987; }","duration":"661.159273ms","start":"2026-02-17T06:35:55.785189Z","end":"2026-02-17T06:35:56.446348Z","steps":["trace[779953194] 'read index received' (duration: 622.936695ms)","trace[779953194] 'applied index is now lower than readState.Index' (duration: 38.222088ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:56.446484Z","caller":"traceutil/trace.go:171","msg":"trace[27989962] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.262104421s","start":"2026-02-17T06:35:55.184374Z","end":"2026-02-17T06:35:56.446479Z","steps":["trace[27989962] 'process raft request' (duration: 161.221813ms)","trace[27989962] 'compare' (duration: 1.099903394s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:56.446518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:55.184359Z","time spent":"1.262141691s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:56.446608Z","caller":"traceutil/trace.go:171","msg":"trace[749281772] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.262041779s","start":"2026-02-17T06:35:55.184562Z","end":"2026-02-17T06:35:56.446604Z","steps":["trace[749281772] 'process raft request' (duration: 1.261721331s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:56.446632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:55.184539Z","time spent":"1.26207904s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:56.446684Z","caller":"traceutil/trace.go:171","msg":"trace[919744621] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.255185024s","start":"2026-02-17T06:35:55.191494Z","end":"2026-02-17T06:35:56.446679Z","steps":["trace[919744621] 'process raft request' (duration: 1.254819796s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:56.446708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:55.191488Z","time spent":"1.255205545s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:56.446771Z","caller":"traceutil/trace.go:171","msg":"trace[1243238395] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"863.495974ms","start":"2026-02-17T06:35:55.58327Z","end":"2026-02-17T06:35:56.446765Z","steps":["trace[1243238395] 'process raft request' (duration: 863.061554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:56.446794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:55.583252Z","time spent":"863.530045ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:56.446869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.681646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T06:35:56.446883Z","caller":"traceutil/trace.go:171","msg":"trace[949074323] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:949; }","duration":"661.730487ms","start":"2026-02-17T06:35:55.785148Z","end":"2026-02-17T06:35:56.446878Z","steps":["trace[949074323] 'agreement among raft nodes before linearized reading' (duration: 661.695896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:56.446898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:55.785125Z","time spent":"661.769998ms","remote":"127.0.0.1:58520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T06:35:56.446979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.425144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:56.446991Z","caller":"traceutil/trace.go:171","msg":"trace[106180729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"605.457436ms","start":"2026-02-17T06:35:55.841529Z","end":"2026-02-17T06:35:56.446987Z","steps":["trace[106180729] 'agreement among raft nodes before linearized reading' (duration: 605.435655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:56.447001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:55.841519Z","time spent":"605.480126ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:35:57.126044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.315769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:35:57.126114Z","caller":"traceutil/trace.go:171","msg":"trace[1627382358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"301.427792ms","start":"2026-02-17T06:35:56.824667Z","end":"2026-02-17T06:35:57.126095Z","steps":["trace[1627382358] 'range keys from in-memory index tree' (duration: 301.124955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:57.126162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:56.824645Z","time spent":"301.509474ms","remote":"127.0.0.1:58322","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":"info","ts":"2026-02-17T06:35:57.469449Z","caller":"traceutil/trace.go:171","msg":"trace[188084344] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"284.088234ms","start":"2026-02-17T06:35:57.185345Z","end":"2026-02-17T06:35:57.469434Z","steps":["trace[188084344] 'read index received' (duration: 283.92565ms)","trace[188084344] 'applied index is now lower than readState.Index' (duration: 162.104µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:57.469526Z","caller":"traceutil/trace.go:171","msg":"trace[1495503086] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"339.960806ms","start":"2026-02-17T06:35:57.12956Z","end":"2026-02-17T06:35:57.469521Z","steps":["trace[1495503086] 'process raft request' (duration: 339.767951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:57.469598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:57.12954Z","time spent":"339.999607ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:57.469874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.510644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:35:57.469934Z","caller":"traceutil/trace.go:171","msg":"trace[1246303599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"284.589366ms","start":"2026-02-17T06:35:57.185321Z","end":"2026-02-17T06:35:57.469911Z","steps":["trace[1246303599] 'agreement among raft nodes before linearized reading' (duration: 284.470013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:57.924889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.682962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:35:57.925151Z","caller":"traceutil/trace.go:171","msg":"trace[272699813] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"452.163554ms","start":"2026-02-17T06:35:57.472973Z","end":"2026-02-17T06:35:57.925137Z","steps":["trace[272699813] 'process raft request' (duration: 318.163455ms)","trace[272699813] 'compare' (duration: 133.490267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:57.925196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:57.472956Z","time spent":"452.217235ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:59.041059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.311128ms","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-17T06:35:59.04114Z","caller":"traceutil/trace.go:171","msg":"trace[694097162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"589.441521ms","start":"2026-02-17T06:35:58.451683Z","end":"2026-02-17T06:35:59.041124Z","steps":["trace[694097162] 'range keys from in-memory index tree' (duration: 589.125183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.041195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:58.451661Z","time spent":"589.524563ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:59.041523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.551703ms","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-17T06:35:59.041607Z","caller":"traceutil/trace.go:171","msg":"trace[65206011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"589.664126ms","start":"2026-02-17T06:35:58.451925Z","end":"2026-02-17T06:35:59.041589Z","steps":["trace[65206011] 'range keys from in-memory index tree' (duration: 589.169725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.041649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:58.451919Z","time spent":"589.720807ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:35:59.041647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.16808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T06:35:59.041681Z","caller":"traceutil/trace.go:171","msg":"trace[1747333579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"588.220671ms","start":"2026-02-17T06:35:58.453452Z","end":"2026-02-17T06:35:59.041673Z","steps":["trace[1747333579] 'range keys from in-memory index tree' (duration: 588.094459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.041704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:58.453443Z","time spent":"588.256982ms","remote":"127.0.0.1:58322","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-17T06:35:59.402877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.777046ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T06:35:59.402979Z","caller":"traceutil/trace.go:171","msg":"trace[1490198945] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"561.426938ms","start":"2026-02-17T06:35:58.841533Z","end":"2026-02-17T06:35:59.40296Z","steps":["trace[1490198945] 'read index received' (duration: 46.021µs)","trace[1490198945] 'applied index is now lower than readState.Index' (duration: 561.379377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:59.403054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.51729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:59.40308Z","caller":"traceutil/trace.go:171","msg":"trace[1045632089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"561.586002ms","start":"2026-02-17T06:35:58.841484Z","end":"2026-02-17T06:35:59.40307Z","steps":["trace[1045632089] 'agreement among raft nodes before linearized reading' (duration: 561.52428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.403116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:58.841456Z","time spent":"561.653013ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T06:35:59.537289Z","caller":"traceutil/trace.go:171","msg":"trace[805305631] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"490.503533ms","start":"2026-02-17T06:35:59.046761Z","end":"2026-02-17T06:35:59.537264Z","steps":["trace[805305631] 'process raft request' (duration: 490.34571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.537436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:59.046742Z","time spent":"490.610037ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:59.964746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.260395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:35:59.964876Z","caller":"traceutil/trace.go:171","msg":"trace[651696133] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:994; }","duration":"557.912153ms","start":"2026-02-17T06:35:59.406948Z","end":"2026-02-17T06:35:59.96486Z","steps":["trace[651696133] 'read index received' (duration: 130.096605ms)","trace[651696133] 'applied index is now lower than readState.Index' (duration: 427.814858ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:35:59.965011Z","caller":"traceutil/trace.go:171","msg":"trace[1053654809] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"917.268207ms","start":"2026-02-17T06:35:59.047735Z","end":"2026-02-17T06:35:59.965004Z","steps":["trace[1053654809] 'process raft request' (duration: 770.673065ms)","trace[1053654809] 'compare' (duration: 146.040829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:35:59.965051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:59.047727Z","time spent":"917.302927ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:35:59.965176Z","caller":"traceutil/trace.go:171","msg":"trace[1332152143] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"913.952787ms","start":"2026-02-17T06:35:59.051217Z","end":"2026-02-17T06:35:59.96517Z","steps":["trace[1332152143] 'process raft request' (duration: 913.595318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.965208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:59.05121Z","time spent":"913.979778ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:35:59.965289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.338384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:35:59.965305Z","caller":"traceutil/trace.go:171","msg":"trace[1209576466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"558.377965ms","start":"2026-02-17T06:35:59.406921Z","end":"2026-02-17T06:35:59.965299Z","steps":["trace[1209576466] 'agreement among raft nodes before linearized reading' (duration: 558.349624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:35:59.965322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:59.406883Z","time spent":"558.435416ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T06:36:00.018369Z","caller":"traceutil/trace.go:171","msg":"trace[460386292] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"128.419165ms","start":"2026-02-17T06:35:59.889932Z","end":"2026-02-17T06:36:00.018351Z","steps":["trace[460386292] 'process raft request' (duration: 128.264742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:00.018819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.577233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:36:00.018933Z","caller":"traceutil/trace.go:171","msg":"trace[1505016883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"544.758318ms","start":"2026-02-17T06:35:59.474163Z","end":"2026-02-17T06:36:00.018922Z","steps":["trace[1505016883] 'agreement among raft nodes before linearized reading' (duration: 544.600764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:00.019096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:35:59.474131Z","time spent":"544.952902ms","remote":"127.0.0.1:58322","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-17T06:36:00.528537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.435023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:36:00.529716Z","caller":"traceutil/trace.go:171","msg":"trace[302826761] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"504.44016ms","start":"2026-02-17T06:36:00.025253Z","end":"2026-02-17T06:36:00.529693Z","steps":["trace[302826761] 'process raft request' (duration: 166.637314ms)","trace[302826761] 'compare' (duration: 336.253289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:36:00.529859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:00.025231Z","time spent":"504.586213ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:36:00.531613Z","caller":"traceutil/trace.go:171","msg":"trace[2044768622] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"425.409611ms","start":"2026-02-17T06:36:00.106189Z","end":"2026-02-17T06:36:00.531598Z","steps":["trace[2044768622] 'read index received' (duration: 85.710139ms)","trace[2044768622] 'applied index is now lower than readState.Index' (duration: 339.698612ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:36:00.532443Z","caller":"traceutil/trace.go:171","msg":"trace[979152770] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"506.618132ms","start":"2026-02-17T06:36:00.02581Z","end":"2026-02-17T06:36:00.532429Z","steps":["trace[979152770] 'process raft request' (duration: 503.844125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:00.532519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.332983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:00.532969Z","caller":"traceutil/trace.go:171","msg":"trace[589540886] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:957; }","duration":"426.818964ms","start":"2026-02-17T06:36:00.106138Z","end":"2026-02-17T06:36:00.532957Z","steps":["trace[589540886] 'agreement among raft nodes before linearized reading' (duration: 426.344323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:00.533083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:00.106117Z","time spent":"426.949858ms","remote":"127.0.0.1:58208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-17T06:36:00.533925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:00.025802Z","time spent":"507.003432ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:36:01.687542Z","caller":"traceutil/trace.go:171","msg":"trace[1253947186] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"141.018908ms","start":"2026-02-17T06:36:01.546504Z","end":"2026-02-17T06:36:01.687522Z","steps":["trace[1253947186] 'process raft request' (duration: 140.887915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:03.596115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.479635ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-17T06:36:03.596317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:03.266966Z","time spent":"329.347633ms","remote":"127.0.0.1:58134","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T06:36:03.597066Z","caller":"traceutil/trace.go:171","msg":"trace[2094748001] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"222.225179ms","start":"2026-02-17T06:36:03.374809Z","end":"2026-02-17T06:36:03.597034Z","steps":["trace[2094748001] 'read index received' (duration: 22.56µs)","trace[2094748001] 'applied index is now lower than readState.Index' (duration: 222.198379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:36:03.597191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.356312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:03.597217Z","caller":"traceutil/trace.go:171","msg":"trace[412601439] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:970; }","duration":"222.427914ms","start":"2026-02-17T06:36:03.374782Z","end":"2026-02-17T06:36:03.59721Z","steps":["trace[412601439] 'agreement among raft nodes before linearized reading' (duration: 222.337302ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:03.851038Z","caller":"traceutil/trace.go:171","msg":"trace[403101910] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"159.266326ms","start":"2026-02-17T06:36:03.691736Z","end":"2026-02-17T06:36:03.851003Z","steps":["trace[403101910] 'read index received' (duration: 110.230738ms)","trace[403101910] 'applied index is now lower than readState.Index' (duration: 49.034438ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:36:03.851378Z","caller":"traceutil/trace.go:171","msg":"trace[1570366906] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"253.595763ms","start":"2026-02-17T06:36:03.597759Z","end":"2026-02-17T06:36:03.851354Z","steps":["trace[1570366906] 'process raft request' (duration: 204.062623ms)","trace[1570366906] 'compare' (duration: 49.048488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:36:03.851454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.690216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:36:03.851811Z","caller":"traceutil/trace.go:171","msg":"trace[1213616629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:971; }","duration":"160.092536ms","start":"2026-02-17T06:36:03.691696Z","end":"2026-02-17T06:36:03.851789Z","steps":["trace[1213616629] 'agreement among raft nodes before linearized reading' (duration: 159.506232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:03.851947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.609712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:03.852031Z","caller":"traceutil/trace.go:171","msg":"trace[2110727512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"141.737575ms","start":"2026-02-17T06:36:03.710278Z","end":"2026-02-17T06:36:03.852016Z","steps":["trace[2110727512] 'agreement among raft nodes before linearized reading' (duration: 141.440798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:04.108513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.735955ms","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-17T06:36:04.108719Z","caller":"traceutil/trace.go:171","msg":"trace[1379751661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"127.007202ms","start":"2026-02-17T06:36:03.981689Z","end":"2026-02-17T06:36:04.108696Z","steps":["trace[1379751661] 'range keys from in-memory index tree' (duration: 126.474469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:04.108511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.267155ms","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-17T06:36:04.108912Z","caller":"traceutil/trace.go:171","msg":"trace[503761925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"124.677715ms","start":"2026-02-17T06:36:03.984214Z","end":"2026-02-17T06:36:04.108892Z","steps":["trace[503761925] 'range keys from in-memory index tree' (duration: 124.108942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:21.232124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.863283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:21.232197Z","caller":"traceutil/trace.go:171","msg":"trace[160202763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"388.957415ms","start":"2026-02-17T06:36:20.843223Z","end":"2026-02-17T06:36:21.23218Z","steps":["trace[160202763] 'range keys from in-memory index tree' (duration: 388.826342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:21.232233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:20.843214Z","time spent":"389.012126ms","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T06:36:21.23266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.075073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:36:21.232716Z","caller":"traceutil/trace.go:171","msg":"trace[939186375] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"389.291443ms","start":"2026-02-17T06:36:20.843412Z","end":"2026-02-17T06:36:21.232704Z","steps":["trace[939186375] 'process raft request' (duration: 41.106657ms)","trace[939186375] 'compare' (duration: 347.881479ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:36:21.232762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:20.843405Z","time spent":"389.332435ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:36:22.301459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.800747ms","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-17T06:36:22.301665Z","caller":"traceutil/trace.go:171","msg":"trace[463715639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"264.035612ms","start":"2026-02-17T06:36:22.037615Z","end":"2026-02-17T06:36:22.301651Z","steps":["trace[463715639] 'range keys from in-memory index tree' (duration: 263.707364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.3026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.588702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-17T06:36:22.302676Z","caller":"traceutil/trace.go:171","msg":"trace[311243633] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1082; }","duration":"201.678205ms","start":"2026-02-17T06:36:22.100979Z","end":"2026-02-17T06:36:22.302657Z","steps":["trace[311243633] 'range keys from in-memory index tree' (duration: 201.48852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.299395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.30198ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:22.520871Z","caller":"traceutil/trace.go:171","msg":"trace[2093874612] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1082; }","duration":"637.782121ms","start":"2026-02-17T06:36:21.883064Z","end":"2026-02-17T06:36:22.520846Z","steps":["trace[2093874612] 'range keys from in-memory index tree' (duration: 416.28755ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:22.58682Z","caller":"traceutil/trace.go:171","msg":"trace[2071957814] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"279.658859ms","start":"2026-02-17T06:36:22.307139Z","end":"2026-02-17T06:36:22.586797Z","steps":["trace[2071957814] 'process raft request' (duration: 279.537226ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:22.682987Z","caller":"traceutil/trace.go:171","msg":"trace[1549456058] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1130; }","duration":"257.856385ms","start":"2026-02-17T06:36:22.425083Z","end":"2026-02-17T06:36:22.682939Z","steps":["trace[1549456058] 'read index received' (duration: 161.763357ms)","trace[1549456058] 'applied index is now lower than readState.Index' (duration: 96.091748ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:36:22.683377Z","caller":"traceutil/trace.go:171","msg":"trace[659501676] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"373.602166ms","start":"2026-02-17T06:36:22.309751Z","end":"2026-02-17T06:36:22.683353Z","steps":["trace[659501676] 'process raft request' (duration: 373.076603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.685298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.176309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:22.685364Z","caller":"traceutil/trace.go:171","msg":"trace[2141887553] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1086; }","duration":"215.275872ms","start":"2026-02-17T06:36:22.470073Z","end":"2026-02-17T06:36:22.685349Z","steps":["trace[2141887553] 'agreement among raft nodes before linearized reading' (duration: 215.18203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.685763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.327473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-17T06:36:22.685796Z","caller":"traceutil/trace.go:171","msg":"trace[1076745587] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1086; }","duration":"128.406795ms","start":"2026-02-17T06:36:22.557378Z","end":"2026-02-17T06:36:22.685785Z","steps":["trace[1076745587] 'agreement among raft nodes before linearized reading' (duration: 128.331353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.686024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:22.309745Z","time spent":"375.192904ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:36:22.68345Z","caller":"traceutil/trace.go:171","msg":"trace[1863511961] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"375.254756ms","start":"2026-02-17T06:36:22.30817Z","end":"2026-02-17T06:36:22.683424Z","steps":["trace[1863511961] 'process raft request' (duration: 372.742195ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:22.683496Z","caller":"traceutil/trace.go:171","msg":"trace[1386050331] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"373.173885ms","start":"2026-02-17T06:36:22.310313Z","end":"2026-02-17T06:36:22.683487Z","steps":["trace[1386050331] 'process raft request' (duration: 372.580681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.691026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:22.310308Z","time spent":"380.606464ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:36:22.683636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.535711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-17T06:36:22.692531Z","caller":"traceutil/trace.go:171","msg":"trace[855039432] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1086; }","duration":"267.476116ms","start":"2026-02-17T06:36:22.425041Z","end":"2026-02-17T06:36:22.692517Z","steps":["trace[855039432] 'agreement among raft nodes before linearized reading' (duration: 258.48305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:22.692953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:22.308151Z","time spent":"384.752874ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:36:31.256365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333077659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:36:31.757434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333077659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:36:32.257744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333077659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:36:32.453996Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.358312107s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T06:36:32.454547Z","caller":"traceutil/trace.go:171","msg":"trace[734112168] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"2.358926952s","start":"2026-02-17T06:36:30.095599Z","end":"2026-02-17T06:36:32.454526Z","steps":["trace[734112168] 'process raft request' (duration: 2.358699917s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.454656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.09558Z","time spent":"2.359009024s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5941,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:36:32.462615Z","caller":"traceutil/trace.go:171","msg":"trace[270956289] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"1.70651463s","start":"2026-02-17T06:36:30.756067Z","end":"2026-02-17T06:36:32.462581Z","steps":["trace[270956289] 'read index received' (duration: 1.699228374s)","trace[270956289] 'applied index is now lower than readState.Index' (duration: 7.284595ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:36:32.463052Z","caller":"traceutil/trace.go:171","msg":"trace[1202442634] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"1.856119804s","start":"2026-02-17T06:36:30.606906Z","end":"2026-02-17T06:36:32.463026Z","steps":["trace[1202442634] 'process raft request' (duration: 1.855328455s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.463264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.606868Z","time spent":"1.856227497s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:36:32.464038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707989315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-17T06:36:32.464067Z","caller":"traceutil/trace.go:171","msg":"trace[1171256589] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1118; }","duration":"1.708067897s","start":"2026-02-17T06:36:30.755992Z","end":"2026-02-17T06:36:32.46406Z","steps":["trace[1171256589] 'agreement among raft nodes before linearized reading' (duration: 1.707484493s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.464086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.755879Z","time spent":"1.70820329s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-17T06:36:32.464265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705499815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-17T06:36:32.464334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584653651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T06:36:32.464354Z","caller":"traceutil/trace.go:171","msg":"trace[162019502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"1.584739184s","start":"2026-02-17T06:36:30.879609Z","end":"2026-02-17T06:36:32.464348Z","steps":["trace[162019502] 'agreement among raft nodes before linearized reading' (duration: 1.584682562s)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:36:32.464345Z","caller":"traceutil/trace.go:171","msg":"trace[348403669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"1.705610578s","start":"2026-02-17T06:36:30.758698Z","end":"2026-02-17T06:36:32.464309Z","steps":["trace[348403669] 'agreement among raft nodes before linearized reading' (duration: 1.705390163s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.46437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.879598Z","time spent":"1.584768904s","remote":"127.0.0.1:58322","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-17T06:36:32.464388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.758691Z","time spent":"1.70569001s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T06:36:32.464443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.132599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:32.464458Z","caller":"traceutil/trace.go:171","msg":"trace[1703836319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"252.178979ms","start":"2026-02-17T06:36:32.212275Z","end":"2026-02-17T06:36:32.464454Z","steps":["trace[1703836319] 'agreement among raft nodes before linearized reading' (duration: 252.153239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.468263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189936479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T06:36:32.468293Z","caller":"traceutil/trace.go:171","msg":"trace[1141279760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"1.190022251s","start":"2026-02-17T06:36:31.278264Z","end":"2026-02-17T06:36:32.468287Z","steps":["trace[1141279760] 'agreement among raft nodes before linearized reading' (duration: 1.18627202s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.468341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:31.278239Z","time spent":"1.190095722s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T06:36:32.46904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709461081s","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-17T06:36:32.469077Z","caller":"traceutil/trace.go:171","msg":"trace[1181694853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"1.709527982s","start":"2026-02-17T06:36:30.759543Z","end":"2026-02-17T06:36:32.469071Z","steps":["trace[1181694853] 'agreement among raft nodes before linearized reading' (duration: 1.70942993s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.469105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.759535Z","time spent":"1.709564353s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T06:36:32.469248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707222986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T06:36:32.469264Z","caller":"traceutil/trace.go:171","msg":"trace[2139709679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"1.707256688s","start":"2026-02-17T06:36:30.762001Z","end":"2026-02-17T06:36:32.469258Z","steps":["trace[2139709679] 'agreement among raft nodes before linearized reading' (duration: 1.707189696s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.46943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.761991Z","time spent":"1.707282598s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:36:32.46458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622690706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T06:36:32.47571Z","caller":"traceutil/trace.go:171","msg":"trace[1914553944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"1.633861124s","start":"2026-02-17T06:36:30.841823Z","end":"2026-02-17T06:36:32.475685Z","steps":["trace[1914553944] 'agreement among raft nodes before linearized reading' (duration: 1.622726127s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:36:32.475791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:36:30.841804Z","time spent":"1.633950276s","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T06:36:50.069054Z","caller":"traceutil/trace.go:171","msg":"trace[1512760871] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"142.590679ms","start":"2026-02-17T06:36:49.926427Z","end":"2026-02-17T06:36:50.069018Z","steps":["trace[1512760871] 'process raft request' (duration: 142.363814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:39.779771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333078983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:38:40.280061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333078983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:38:40.780762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333078983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:38:41.281837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333078983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:38:41.782439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548400333078983,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T06:38:42.10309Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.864561595s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T06:38:42.103641Z","caller":"traceutil/trace.go:171","msg":"trace[1471179004] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"2.86521044s","start":"2026-02-17T06:38:39.238404Z","end":"2026-02-17T06:38:42.103615Z","steps":["trace[1471179004] 'process raft request' (duration: 2.865061607s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.103861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.238382Z","time spent":"2.865395194s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T06:38:42.106862Z","caller":"traceutil/trace.go:171","msg":"trace[779185785] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1710; }","duration":"2.827599807s","start":"2026-02-17T06:38:39.279247Z","end":"2026-02-17T06:38:42.106846Z","steps":["trace[779185785] 'read index received' (duration: 2.824137774s)","trace[779185785] 'applied index is now lower than readState.Index' (duration: 3.461183ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:38:42.107117Z","caller":"traceutil/trace.go:171","msg":"trace[799272832] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"1.49488207s","start":"2026-02-17T06:38:40.612222Z","end":"2026-02-17T06:38:42.107104Z","steps":["trace[799272832] 'process raft request' (duration: 1.494535172s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.107251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:40.61219Z","time spent":"1.494993173s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T06:38:42.107423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64351705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-17T06:38:42.107559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705615469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T06:38:42.107633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.828401977s","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-17T06:38:42.107667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.006849ms","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":"warn","ts":"2026-02-17T06:38:42.107704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.040902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T06:38:42.10773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266487538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T06:38:42.107766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278754473s","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-17T06:38:42.107835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.515932908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-02-17T06:38:42.107891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770522477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-17T06:38:42.107922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123175061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T06:38:42.110964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.634933804s","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-17T06:38:42.11108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.616963623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-17T06:38:42.111154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.641587384s","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-17T06:38:42.111859Z","caller":"traceutil/trace.go:171","msg":"trace[1533033873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1637; }","duration":"2.647993177s","start":"2026-02-17T06:38:39.463851Z","end":"2026-02-17T06:38:42.111844Z","steps":["trace[1533033873] 'agreement among raft nodes before linearized reading' (duration: 2.643468779s)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:38:42.111887Z","caller":"traceutil/trace.go:171","msg":"trace[1626235952] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1637; }","duration":"1.710002904s","start":"2026-02-17T06:38:40.401877Z","end":"2026-02-17T06:38:42.11188Z","steps":["trace[1626235952] 'agreement among raft nodes before linearized reading' (duration: 1.705609069s)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:38:42.112015Z","caller":"traceutil/trace.go:171","msg":"trace[583570178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1637; }","duration":"2.832830153s","start":"2026-02-17T06:38:39.279174Z","end":"2026-02-17T06:38:42.112004Z","steps":["trace[583570178] 'agreement among raft nodes before linearized reading' (duration: 2.828406997s)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:38:42.113012Z","caller":"traceutil/trace.go:171","msg":"trace[2056698765] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1637; }","duration":"382.381238ms","start":"2026-02-17T06:38:41.730622Z","end":"2026-02-17T06:38:42.113003Z","steps":["trace[2056698765] 'agreement among raft nodes before linearized reading' (duration: 377.024549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.113806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:41.730608Z","time spent":"383.190777ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-02-17T06:38:42.11318Z","caller":"traceutil/trace.go:171","msg":"trace[513834727] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1637; }","duration":"425.547014ms","start":"2026-02-17T06:38:41.687626Z","end":"2026-02-17T06:38:42.113173Z","steps":["trace[513834727] 'agreement among raft nodes before linearized reading' (duration: 420.056252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.114077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:41.687606Z","time spent":"426.460235ms","remote":"127.0.0.1:58336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-17T06:38:42.113201Z","caller":"traceutil/trace.go:171","msg":"trace[1928148234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1637; }","duration":"1.27196958s","start":"2026-02-17T06:38:40.841226Z","end":"2026-02-17T06:38:42.113196Z","steps":["trace[1928148234] 'agreement among raft nodes before linearized reading' (duration: 1.266491048s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.114358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:40.84121Z","time spent":"1.273117738s","remote":"127.0.0.1:58120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T06:38:42.113313Z","caller":"traceutil/trace.go:171","msg":"trace[2028149968] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1637; }","duration":"2.521473181s","start":"2026-02-17T06:38:39.59183Z","end":"2026-02-17T06:38:42.113303Z","steps":["trace[2028149968] 'agreement among raft nodes before linearized reading' (duration: 2.515956119s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.114835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.591791Z","time spent":"2.523033369s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-17T06:38:42.113332Z","caller":"traceutil/trace.go:171","msg":"trace[555140212] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1637; }","duration":"1.284382018s","start":"2026-02-17T06:38:40.828946Z","end":"2026-02-17T06:38:42.113328Z","steps":["trace[555140212] 'agreement among raft nodes before linearized reading' (duration: 1.278795454s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.115256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:40.828925Z","time spent":"1.286248592s","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-17T06:38:42.113348Z","caller":"traceutil/trace.go:171","msg":"trace[943217466] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1637; }","duration":"1.776050189s","start":"2026-02-17T06:38:40.337293Z","end":"2026-02-17T06:38:42.113344Z","steps":["trace[943217466] 'agreement among raft nodes before linearized reading' (duration: 1.770556127s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.11572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:40.337269Z","time spent":"1.778439747s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-17T06:38:42.113363Z","caller":"traceutil/trace.go:171","msg":"trace[1185452645] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1637; }","duration":"2.128618391s","start":"2026-02-17T06:38:39.984741Z","end":"2026-02-17T06:38:42.113359Z","steps":["trace[1185452645] 'agreement among raft nodes before linearized reading' (duration: 2.123163261s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.116385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.984651Z","time spent":"2.131721766s","remote":"127.0.0.1:58294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-17T06:38:42.113525Z","caller":"traceutil/trace.go:171","msg":"trace[533452565] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1637; }","duration":"2.619427802s","start":"2026-02-17T06:38:39.494089Z","end":"2026-02-17T06:38:42.113516Z","steps":["trace[533452565] 'agreement among raft nodes before linearized reading' (duration: 2.616905522s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.11685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.494079Z","time spent":"2.622759572s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-17T06:38:42.113546Z","caller":"traceutil/trace.go:171","msg":"trace[1650418242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1637; }","duration":"2.637551667s","start":"2026-02-17T06:38:39.475987Z","end":"2026-02-17T06:38:42.113539Z","steps":["trace[1650418242] 'agreement among raft nodes before linearized reading' (duration: 2.631954303s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.117375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.475981Z","time spent":"2.641387259s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T06:38:42.113561Z","caller":"traceutil/trace.go:171","msg":"trace[702593365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1637; }","duration":"2.644016092s","start":"2026-02-17T06:38:39.46954Z","end":"2026-02-17T06:38:42.113557Z","steps":["trace[702593365] 'agreement among raft nodes before linearized reading' (duration: 2.641561223s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:38:42.117608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.469527Z","time spent":"2.648074049s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T06:38:42.113717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:40.401848Z","time spent":"1.711816568s","remote":"127.0.0.1:58394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-17T06:38:42.11374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.46383Z","time spent":"2.649905323s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T06:38:42.113762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T06:38:39.279142Z","time spent":"2.834615616s","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T06:39:46.668734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.008245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T06:39:46.668913Z","caller":"traceutil/trace.go:171","msg":"trace[1752035607] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2021; }","duration":"137.23617ms","start":"2026-02-17T06:39:46.53166Z","end":"2026-02-17T06:39:46.668897Z","steps":["trace[1752035607] 'read index received' (duration: 32.968749ms)","trace[1752035607] 'applied index is now lower than readState.Index' (duration: 104.266461ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T06:39:46.669089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.427534ms","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-17T06:39:46.66911Z","caller":"traceutil/trace.go:171","msg":"trace[828295336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1934; }","duration":"137.474715ms","start":"2026-02-17T06:39:46.531626Z","end":"2026-02-17T06:39:46.669101Z","steps":["trace[828295336] 'agreement among raft nodes before linearized reading' (duration: 137.310881ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:39:46.669362Z","caller":"traceutil/trace.go:171","msg":"trace[515747404] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"143.269162ms","start":"2026-02-17T06:39:46.526083Z","end":"2026-02-17T06:39:46.669352Z","steps":["trace[515747404] 'process raft request' (duration: 38.475828ms)","trace[515747404] 'compare' (duration: 103.78641ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:39:46.669463Z","caller":"traceutil/trace.go:171","msg":"trace[999081803] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"139.423561ms","start":"2026-02-17T06:39:46.530032Z","end":"2026-02-17T06:39:46.669456Z","steps":["trace[999081803] 'process raft request' (duration: 138.817057ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:39:47.565095Z","caller":"traceutil/trace.go:171","msg":"trace[1625165710] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"260.146151ms","start":"2026-02-17T06:39:47.304918Z","end":"2026-02-17T06:39:47.565065Z","steps":["trace[1625165710] 'process raft request' (duration: 259.932776ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T06:39:47.585047Z","caller":"traceutil/trace.go:171","msg":"trace[1434316281] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2025; }","duration":"153.489043ms","start":"2026-02-17T06:39:47.431538Z","end":"2026-02-17T06:39:47.585027Z","steps":["trace[1434316281] 'read index received' (duration: 133.370128ms)","trace[1434316281] 'applied index is now lower than readState.Index' (duration: 20.117925ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T06:39:47.585492Z","caller":"traceutil/trace.go:171","msg":"trace[950912209] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"277.254934ms","start":"2026-02-17T06:39:47.308225Z","end":"2026-02-17T06:39:47.58548Z","steps":["trace[950912209] 'process raft request' (duration: 276.672291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T06:39:47.58594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.356813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T06:39:47.587283Z","caller":"traceutil/trace.go:171","msg":"trace[1015514719] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1938; }","duration":"154.569498ms","start":"2026-02-17T06:39:47.431469Z","end":"2026-02-17T06:39:47.586039Z","steps":["trace[1015514719] 'agreement among raft nodes before linearized reading' (duration: 154.391333ms)"],"step_count":1}