{"level":"warn","ts":"2026-02-08T22:13:56.962766Z","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-08T22:13:56.963111Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-08T22:13:56.963343Z","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-08T22:13:56.96336Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-08T22:13:56.963457Z","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-08T22:13:56.964191Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-08T22:13:56.964823Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-08T22:13:56.967935Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.565801ms"} {"level":"info","ts":"2026-02-08T22:13:56.986328Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-08T22:13:56.986485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:13:56.986511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-08T22:13:56.986573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:13:56.98658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-08T22:13:56.986699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-08T22:13:56.9927Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:13:56.995659Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:13:56.998409Z","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-08T22:13:57.002161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:13:57.002616Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:13:57.002774Z","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-08T22:13:57.002802Z","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-08T22:13:57.00281Z","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-08T22:13:57.003498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-08T22:13:57.003603Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-08T22:13:57.008354Z","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-08T22:13:57.009665Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-08T22:13:57.009683Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-08T22:13:57.009911Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:13:57.009936Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:13:57.187341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:13:57.187417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:13:57.187433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-08T22:13:57.187446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:13:57.187451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-08T22:13:57.187475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-08T22:13:57.187484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-08T22:13:57.229389Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:57.232707Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:13:57.23276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:13:57.234715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-08T22:13:57.234959Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:57.235076Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:57.235094Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:57.235105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:13:57.240698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T22:13:57.251357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:13:57.251374Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:14:00.56058Z","caller":"traceutil/trace.go:171","msg":"trace[1574466819] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"155.149603ms","start":"2026-02-08T22:14:00.405191Z","end":"2026-02-08T22:14:00.56034Z","steps":["trace[1574466819] 'process raft request' (duration: 124.620774ms)","trace[1574466819] 'compare' (duration: 30.368415ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:01.22872Z","caller":"traceutil/trace.go:171","msg":"trace[562726974] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:182; }","duration":"182.4943ms","start":"2026-02-08T22:14:01.046202Z","end":"2026-02-08T22:14:01.228696Z","steps":["trace[562726974] 'read index received' (duration: 122.55818ms)","trace[562726974] 'applied index is now lower than readState.Index' (duration: 59.93481ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:01.229009Z","caller":"traceutil/trace.go:171","msg":"trace[656607280] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"187.597983ms","start":"2026-02-08T22:14:01.041224Z","end":"2026-02-08T22:14:01.228821Z","steps":["trace[656607280] 'process raft request' (duration: 127.605432ms)","trace[656607280] 'compare' (duration: 59.628471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:01.229948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.396113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:01.230032Z","caller":"traceutil/trace.go:171","msg":"trace[1811498569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:179; }","duration":"183.835044ms","start":"2026-02-08T22:14:01.046181Z","end":"2026-02-08T22:14:01.230016Z","steps":["trace[1811498569] 'agreement among raft nodes before linearized reading' (duration: 183.361002ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:03.720963Z","caller":"traceutil/trace.go:171","msg":"trace[1830693646] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:267; }","duration":"104.288581ms","start":"2026-02-08T22:14:03.616653Z","end":"2026-02-08T22:14:03.720942Z","steps":["trace[1830693646] 'read index received' (duration: 32.086ms)","trace[1830693646] 'applied index is now lower than readState.Index' (duration: 72.201241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:03.721423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.748403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:03.721689Z","caller":"traceutil/trace.go:171","msg":"trace[1264982711] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:265; }","duration":"105.052541ms","start":"2026-02-08T22:14:03.616622Z","end":"2026-02-08T22:14:03.721674Z","steps":["trace[1264982711] 'agreement among raft nodes before linearized reading' (duration: 104.685652ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:03.722241Z","caller":"traceutil/trace.go:171","msg":"trace[423693925] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"109.808476ms","start":"2026-02-08T22:14:03.612418Z","end":"2026-02-08T22:14:03.722227Z","steps":["trace[423693925] 'process raft request' (duration: 108.192334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:06.584276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.490027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:06.584379Z","caller":"traceutil/trace.go:171","msg":"trace[616066875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:295; }","duration":"117.638731ms","start":"2026-02-08T22:14:06.466718Z","end":"2026-02-08T22:14:06.584357Z","steps":["trace[616066875] 'range keys from in-memory index tree' (duration: 117.431205ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:12.613425Z","caller":"traceutil/trace.go:171","msg":"trace[869620670] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"545.840436ms","start":"2026-02-08T22:14:12.06726Z","end":"2026-02-08T22:14:12.6131Z","steps":["trace[869620670] 'process raft request' (duration: 545.63403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:12.614084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:12.067247Z","time spent":"546.250596ms","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:13.503153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557469526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:14:14.004076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557469526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:14:14.246488Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.615058899s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T22:14:14.248001Z","caller":"traceutil/trace.go:171","msg":"trace[2039948395] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.61663374s","start":"2026-02-08T22:14:12.631336Z","end":"2026-02-08T22:14:14.247969Z","steps":["trace[2039948395] 'process raft request' (duration: 1.616541727s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.2481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:12.631309Z","time spent":"1.616738443s","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:14.281824Z","caller":"traceutil/trace.go:171","msg":"trace[1882762213] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"1.279563193s","start":"2026-02-08T22:14:13.002237Z","end":"2026-02-08T22:14:14.2818Z","steps":["trace[1882762213] 'read index received' (duration: 1.246152498s)","trace[1882762213] 'applied index is now lower than readState.Index' (duration: 33.409995ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:14.282023Z","caller":"traceutil/trace.go:171","msg":"trace[490661396] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"914.303966ms","start":"2026-02-08T22:14:13.36771Z","end":"2026-02-08T22:14:14.282014Z","steps":["trace[490661396] 'process raft request' (duration: 906.200544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.282137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:13.367696Z","time spent":"914.345967ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:14.282239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.360317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T22:14:14.282319Z","caller":"traceutil/trace.go:171","msg":"trace[1906939915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"985.495191ms","start":"2026-02-08T22:14:13.296806Z","end":"2026-02-08T22:14:14.282302Z","steps":["trace[1906939915] 'agreement among raft nodes before linearized reading' (duration: 985.330696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.282341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.078878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:14.28239Z","caller":"traceutil/trace.go:171","msg":"trace[1490406200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"862.1602ms","start":"2026-02-08T22:14:13.420221Z","end":"2026-02-08T22:14:14.282382Z","steps":["trace[1490406200] 'agreement among raft nodes before linearized reading' (duration: 862.098168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.282391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:13.296787Z","time spent":"985.594323ms","remote":"127.0.0.1:59474","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-08T22:14:14.28241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:13.420204Z","time spent":"862.202411ms","remote":"127.0.0.1:59174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:14:14.282508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280276801s","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-08T22:14:14.282525Z","caller":"traceutil/trace.go:171","msg":"trace[1988940720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"1.280329772s","start":"2026-02-08T22:14:13.002191Z","end":"2026-02-08T22:14:14.282521Z","steps":["trace[1988940720] 'agreement among raft nodes before linearized reading' (duration: 1.280297392s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.282544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:13.002171Z","time spent":"1.280368783s","remote":"127.0.0.1:59474","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-08T22:14:14.282664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.124958ms","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-08T22:14:14.282681Z","caller":"traceutil/trace.go:171","msg":"trace[507664672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:369; }","duration":"970.18169ms","start":"2026-02-08T22:14:13.312494Z","end":"2026-02-08T22:14:14.282676Z","steps":["trace[507664672] 'agreement among raft nodes before linearized reading' (duration: 970.151659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.282697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:13.312474Z","time spent":"970.21951ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:14:14.282752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.605549ms","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-08T22:14:14.282766Z","caller":"traceutil/trace.go:171","msg":"trace[30643785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"965.641791ms","start":"2026-02-08T22:14:13.31712Z","end":"2026-02-08T22:14:14.282761Z","steps":["trace[30643785] 'agreement among raft nodes before linearized reading' (duration: 965.61616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.282781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:13.317107Z","time spent":"965.670791ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-08T22:14:16.662929Z","caller":"traceutil/trace.go:171","msg":"trace[1063225525] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"137.46259ms","start":"2026-02-08T22:14:16.525445Z","end":"2026-02-08T22:14:16.662908Z","steps":["trace[1063225525] 'process raft request' (duration: 137.334777ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:29.718065Z","caller":"traceutil/trace.go:171","msg":"trace[766372984] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"146.437935ms","start":"2026-02-08T22:14:29.571603Z","end":"2026-02-08T22:14:29.718041Z","steps":["trace[766372984] 'process raft request' (duration: 146.314631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:38.730128Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.171868528s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T22:14:38.730484Z","caller":"traceutil/trace.go:171","msg":"trace[1054060196] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"238.526077ms","start":"2026-02-08T22:14:38.491935Z","end":"2026-02-08T22:14:38.730461Z","steps":["trace[1054060196] 'read index received' (duration: 238.307221ms)","trace[1054060196] 'applied index is now lower than readState.Index' (duration: 218.026µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:38.730843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.996785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T22:14:38.730894Z","caller":"traceutil/trace.go:171","msg":"trace[1363088608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"182.090398ms","start":"2026-02-08T22:14:38.548793Z","end":"2026-02-08T22:14:38.730884Z","steps":["trace[1363088608] 'agreement among raft nodes before linearized reading' (duration: 181.940284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:38.730889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.925817ms","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-08T22:14:38.730979Z","caller":"traceutil/trace.go:171","msg":"trace[1055624514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"239.05105ms","start":"2026-02-08T22:14:38.491903Z","end":"2026-02-08T22:14:38.730954Z","steps":["trace[1055624514] 'agreement among raft nodes before linearized reading' (duration: 238.686331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:38.731223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.350579ms","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-08T22:14:38.731258Z","caller":"traceutil/trace.go:171","msg":"trace[1164197095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"168.42637ms","start":"2026-02-08T22:14:38.562822Z","end":"2026-02-08T22:14:38.731249Z","steps":["trace[1164197095] 'agreement among raft nodes before linearized reading' (duration: 168.339938ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:38.731266Z","caller":"traceutil/trace.go:171","msg":"trace[1511769774] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.251067262s","start":"2026-02-08T22:14:37.480186Z","end":"2026-02-08T22:14:38.731253Z","steps":["trace[1511769774] 'process raft request' (duration: 1.250118667s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:38.731366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:37.480147Z","time spent":"1.251150514s","remote":"127.0.0.1:59386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:38.731421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.723008ms","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-08T22:14:38.731455Z","caller":"traceutil/trace.go:171","msg":"trace[847814438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"176.792829ms","start":"2026-02-08T22:14:38.554654Z","end":"2026-02-08T22:14:38.731447Z","steps":["trace[847814438] 'agreement among raft nodes before linearized reading' (duration: 176.719717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.573444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.10387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:39.57353Z","caller":"traceutil/trace.go:171","msg":"trace[424288530] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"830.488268ms","start":"2026-02-08T22:14:38.743026Z","end":"2026-02-08T22:14:39.573514Z","steps":["trace[424288530] 'process raft request' (duration: 208.11269ms)","trace[424288530] 'compare' (duration: 621.942486ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:39.573571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.743008Z","time spent":"830.54372ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:39.613674Z","caller":"traceutil/trace.go:171","msg":"trace[517562151] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"870.596738ms","start":"2026-02-08T22:14:38.743052Z","end":"2026-02-08T22:14:39.613649Z","steps":["trace[517562151] 'process raft request' (duration: 870.421843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.613833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.743032Z","time spent":"870.708161ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:39.614135Z","caller":"traceutil/trace.go:171","msg":"trace[942014511] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"870.874756ms","start":"2026-02-08T22:14:38.743123Z","end":"2026-02-08T22:14:39.613997Z","steps":["trace[942014511] 'process raft request' (duration: 870.488496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.61424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.743118Z","time spent":"871.067891ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:39.614242Z","caller":"traceutil/trace.go:171","msg":"trace[856801982] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:512; }","duration":"194.072132ms","start":"2026-02-08T22:14:39.420155Z","end":"2026-02-08T22:14:39.614227Z","steps":["trace[856801982] 'read index received' (duration: 193.171288ms)","trace[856801982] 'applied index is now lower than readState.Index' (duration: 899.974µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:39.614319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.156414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:39.614384Z","caller":"traceutil/trace.go:171","msg":"trace[19661996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"194.215826ms","start":"2026-02-08T22:14:39.420124Z","end":"2026-02-08T22:14:39.61434Z","steps":["trace[19661996] 'agreement among raft nodes before linearized reading' (duration: 194.150114ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:39.614595Z","caller":"traceutil/trace.go:171","msg":"trace[1389977372] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"871.42212ms","start":"2026-02-08T22:14:38.743161Z","end":"2026-02-08T22:14:39.614583Z","steps":["trace[1389977372] 'process raft request' (duration: 870.770063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.620099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.743155Z","time spent":"871.468931ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:43.457076Z","caller":"traceutil/trace.go:171","msg":"trace[1300828405] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"111.941212ms","start":"2026-02-08T22:14:43.345106Z","end":"2026-02-08T22:14:43.457048Z","steps":["trace[1300828405] 'process raft request' (duration: 25.540379ms)","trace[1300828405] 'compare' (duration: 86.011303ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:43.785477Z","caller":"traceutil/trace.go:171","msg":"trace[1698923501] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"107.854804ms","start":"2026-02-08T22:14:43.677607Z","end":"2026-02-08T22:14:43.785462Z","steps":["trace[1698923501] 'process raft request' (duration: 107.556856ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:44.823891Z","caller":"traceutil/trace.go:171","msg":"trace[354077395] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"152.563236ms","start":"2026-02-08T22:14:44.671304Z","end":"2026-02-08T22:14:44.823868Z","steps":["trace[354077395] 'process raft request' (duration: 149.351532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:50.91934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557469993,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:14:50.987151Z","caller":"traceutil/trace.go:171","msg":"trace[1772044418] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"568.246823ms","start":"2026-02-08T22:14:50.418889Z","end":"2026-02-08T22:14:50.987136Z","steps":["trace[1772044418] 'read index received' (duration: 568.091079ms)","trace[1772044418] 'applied index is now lower than readState.Index' (duration: 155.264µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:50.987255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.349426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:50.98728Z","caller":"traceutil/trace.go:171","msg":"trace[1934517989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"568.405627ms","start":"2026-02-08T22:14:50.418861Z","end":"2026-02-08T22:14:50.987266Z","steps":["trace[1934517989] 'agreement among raft nodes before linearized reading' (duration: 568.346125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:50.987298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:50.418848Z","time spent":"568.446458ms","remote":"127.0.0.1:59186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:14:50.987553Z","caller":"traceutil/trace.go:171","msg":"trace[1710528305] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"869.715528ms","start":"2026-02-08T22:14:50.11783Z","end":"2026-02-08T22:14:50.987546Z","steps":["trace[1710528305] 'process raft request' (duration: 869.210895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:50.987601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:50.117815Z","time spent":"869.75169ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:51.994132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.641623ms","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-08T22:14:52.000662Z","caller":"traceutil/trace.go:171","msg":"trace[2023536551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:602; }","duration":"499.237085ms","start":"2026-02-08T22:14:51.501408Z","end":"2026-02-08T22:14:52.000645Z","steps":["trace[2023536551] 'range keys from in-memory index tree' (duration: 492.513459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.000843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:51.501392Z","time spent":"499.44003ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:14:51.994954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.928411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T22:14:52.002269Z","caller":"traceutil/trace.go:171","msg":"trace[118967945] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:602; }","duration":"652.274964ms","start":"2026-02-08T22:14:51.34998Z","end":"2026-02-08T22:14:52.002255Z","steps":["trace[118967945] 'range keys from in-memory index tree' (duration: 644.794438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.002463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:51.349941Z","time spent":"652.468289ms","remote":"127.0.0.1:59212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-02-08T22:14:51.996392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.535834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:14:51.998216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.17264ms","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-08T22:14:52.003956Z","caller":"traceutil/trace.go:171","msg":"trace[1351371463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"300.912431ms","start":"2026-02-08T22:14:51.703009Z","end":"2026-02-08T22:14:52.003921Z","steps":["trace[1351371463] 'range keys from in-memory index tree' (duration: 295.095178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.004031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:51.702995Z","time spent":"301.018184ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T22:14:52.004314Z","caller":"traceutil/trace.go:171","msg":"trace[553410492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"582.072264ms","start":"2026-02-08T22:14:51.420821Z","end":"2026-02-08T22:14:52.002894Z","steps":["trace[553410492] 'range keys from in-memory index tree' (duration: 575.479132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.004684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:51.420807Z","time spent":"583.868352ms","remote":"127.0.0.1:59174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:14:51.998334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.33116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T22:14:52.005557Z","caller":"traceutil/trace.go:171","msg":"trace[1682007340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"298.451307ms","start":"2026-02-08T22:14:51.70699Z","end":"2026-02-08T22:14:52.005441Z","steps":["trace[1682007340] 'range keys from in-memory index tree' (duration: 291.289209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:51.99998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.138893ms","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-08T22:14:52.005746Z","caller":"traceutil/trace.go:171","msg":"trace[1718882419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"181.922465ms","start":"2026-02-08T22:14:51.823817Z","end":"2026-02-08T22:14:52.005739Z","steps":["trace[1718882419] 'range keys from in-memory index tree' (duration: 176.076811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.00007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.116482ms","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-08T22:14:52.006145Z","caller":"traceutil/trace.go:171","msg":"trace[1681463436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"182.151071ms","start":"2026-02-08T22:14:51.823942Z","end":"2026-02-08T22:14:52.006093Z","steps":["trace[1681463436] 'range keys from in-memory index tree' (duration: 176.076862ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:52.285156Z","caller":"traceutil/trace.go:171","msg":"trace[667334423] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:625; }","duration":"278.275957ms","start":"2026-02-08T22:14:52.006864Z","end":"2026-02-08T22:14:52.28514Z","steps":["trace[667334423] 'read index received' (duration: 278.270127ms)","trace[667334423] 'applied index is now lower than readState.Index' (duration: 4.83µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:52.285312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.434511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:52.285356Z","caller":"traceutil/trace.go:171","msg":"trace[2112622666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"278.498143ms","start":"2026-02-08T22:14:52.00685Z","end":"2026-02-08T22:14:52.285349Z","steps":["trace[2112622666] 'agreement among raft nodes before linearized reading' (duration: 278.422441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.505636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.661257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:52.506041Z","caller":"traceutil/trace.go:171","msg":"trace[579621803] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"497.007746ms","start":"2026-02-08T22:14:52.00902Z","end":"2026-02-08T22:14:52.506027Z","steps":["trace[579621803] 'process raft request' (duration: 290.890167ms)","trace[579621803] 'compare' (duration: 205.556934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:52.506084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:52.009004Z","time spent":"497.058428ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:52.506183Z","caller":"traceutil/trace.go:171","msg":"trace[382997323] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"495.99678ms","start":"2026-02-08T22:14:52.01018Z","end":"2026-02-08T22:14:52.506177Z","steps":["trace[382997323] 'process raft request' (duration: 495.514897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.506215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:52.010174Z","time spent":"496.02342ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:52.506284Z","caller":"traceutil/trace.go:171","msg":"trace[389444629] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"495.589699ms","start":"2026-02-08T22:14:52.010689Z","end":"2026-02-08T22:14:52.506279Z","steps":["trace[389444629] 'process raft request' (duration: 495.038715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.506312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:52.010683Z","time spent":"495.61416ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:52.506407Z","caller":"traceutil/trace.go:171","msg":"trace[1528336984] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"495.64352ms","start":"2026-02-08T22:14:52.010758Z","end":"2026-02-08T22:14:52.506401Z","steps":["trace[1528336984] 'process raft request' (duration: 494.989033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.506439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:52.010745Z","time spent":"495.675741ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:52.506513Z","caller":"traceutil/trace.go:171","msg":"trace[2059408629] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"494.773907ms","start":"2026-02-08T22:14:52.011733Z","end":"2026-02-08T22:14:52.506507Z","steps":["trace[2059408629] 'process raft request' (duration: 494.031218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:52.50654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:52.011727Z","time spent":"494.798408ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:52.506737Z","caller":"traceutil/trace.go:171","msg":"trace[1333529701] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"217.372243ms","start":"2026-02-08T22:14:52.289314Z","end":"2026-02-08T22:14:52.506687Z","steps":["trace[1333529701] 'process raft request' (duration: 216.472719ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:54.632474Z","caller":"traceutil/trace.go:171","msg":"trace[858200355] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"114.441347ms","start":"2026-02-08T22:14:54.517921Z","end":"2026-02-08T22:14:54.632363Z","steps":["trace[858200355] 'process raft request' (duration: 114.299703ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:54.634506Z","caller":"traceutil/trace.go:171","msg":"trace[257767405] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"110.518394ms","start":"2026-02-08T22:14:54.523664Z","end":"2026-02-08T22:14:54.634182Z","steps":["trace[257767405] 'process raft request' (duration: 110.404752ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:54.634814Z","caller":"traceutil/trace.go:171","msg":"trace[1432716165] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"116.214214ms","start":"2026-02-08T22:14:54.518588Z","end":"2026-02-08T22:14:54.634802Z","steps":["trace[1432716165] 'process raft request' (duration: 114.616632ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:54.635619Z","caller":"traceutil/trace.go:171","msg":"trace[1292839168] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"116.883941ms","start":"2026-02-08T22:14:54.518722Z","end":"2026-02-08T22:14:54.635606Z","steps":["trace[1292839168] 'process raft request' (duration: 114.857098ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:54.635863Z","caller":"traceutil/trace.go:171","msg":"trace[1770027010] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"115.178546ms","start":"2026-02-08T22:14:54.520676Z","end":"2026-02-08T22:14:54.635854Z","steps":["trace[1770027010] 'process raft request' (duration: 112.992429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:56.738424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.812286ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T22:14:56.738507Z","caller":"traceutil/trace.go:171","msg":"trace[571929968] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"101.063147ms","start":"2026-02-08T22:14:56.637427Z","end":"2026-02-08T22:14:56.73849Z","steps":["trace[571929968] 'read index received' (duration: 28.691µs)","trace[571929968] 'applied index is now lower than readState.Index' (duration: 101.033226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:56.73885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.388046ms","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-08T22:14:56.73893Z","caller":"traceutil/trace.go:171","msg":"trace[2070785696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"101.492418ms","start":"2026-02-08T22:14:56.637399Z","end":"2026-02-08T22:14:56.738892Z","steps":["trace[2070785696] 'agreement among raft nodes before linearized reading' (duration: 101.294983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.691334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.725121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:57.691527Z","caller":"traceutil/trace.go:171","msg":"trace[1774512851] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"947.267179ms","start":"2026-02-08T22:14:56.744239Z","end":"2026-02-08T22:14:57.691507Z","steps":["trace[1774512851] 'process raft request' (duration: 284.968833ms)","trace[1774512851] 'compare' (duration: 661.352631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:57.691624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:56.744184Z","time spent":"947.388403ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:57.69166Z","caller":"traceutil/trace.go:171","msg":"trace[508790645] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:652; }","duration":"269.080657ms","start":"2026-02-08T22:14:57.42256Z","end":"2026-02-08T22:14:57.69164Z","steps":["trace[508790645] 'read index received' (duration: 264.027975ms)","trace[508790645] 'applied index is now lower than readState.Index' (duration: 5.051762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:57.691744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.17952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:57.691771Z","caller":"traceutil/trace.go:171","msg":"trace[102827470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"269.250792ms","start":"2026-02-08T22:14:57.422512Z","end":"2026-02-08T22:14:57.691762Z","steps":["trace[102827470] 'agreement among raft nodes before linearized reading' (duration: 269.18213ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:57.691781Z","caller":"traceutil/trace.go:171","msg":"trace[532587741] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"946.108289ms","start":"2026-02-08T22:14:56.745664Z","end":"2026-02-08T22:14:57.691773Z","steps":["trace[532587741] 'process raft request' (duration: 945.864743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.69181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:56.745656Z","time spent":"946.138609ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:57.691911Z","caller":"traceutil/trace.go:171","msg":"trace[1936979752] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"947.614198ms","start":"2026-02-08T22:14:56.74429Z","end":"2026-02-08T22:14:57.691904Z","steps":["trace[1936979752] 'process raft request' (duration: 947.153286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.691939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:56.744285Z","time spent":"947.639149ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:57.691947Z","caller":"traceutil/trace.go:171","msg":"trace[1142032963] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"946.196991ms","start":"2026-02-08T22:14:56.745742Z","end":"2026-02-08T22:14:57.691939Z","steps":["trace[1142032963] 'process raft request' (duration: 945.821671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.691985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:56.745718Z","time spent":"946.246063ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:57.692098Z","caller":"traceutil/trace.go:171","msg":"trace[631835654] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"854.328315ms","start":"2026-02-08T22:14:56.837764Z","end":"2026-02-08T22:14:57.692093Z","steps":["trace[631835654] 'process raft request' (duration: 853.839112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.692143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:56.837742Z","time spent":"854.388946ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:14:57.89803Z","caller":"traceutil/trace.go:171","msg":"trace[297804898] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"135.56894ms","start":"2026-02-08T22:14:57.762441Z","end":"2026-02-08T22:14:57.89801Z","steps":["trace[297804898] 'process raft request' (duration: 125.560738ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:07.199258Z","caller":"traceutil/trace.go:171","msg":"trace[654963252] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"353.079541ms","start":"2026-02-08T22:15:06.84616Z","end":"2026-02-08T22:15:07.199239Z","steps":["trace[654963252] 'process raft request' (duration: 352.985648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:07.199375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:06.846147Z","time spent":"353.166953ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:07.734653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.788153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:07.734696Z","caller":"traceutil/trace.go:171","msg":"trace[1785377331] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:701; }","duration":"431.857374ms","start":"2026-02-08T22:15:07.302829Z","end":"2026-02-08T22:15:07.734686Z","steps":["trace[1785377331] 'range keys from in-memory index tree' (duration: 431.752142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:07.734717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.302818Z","time spent":"431.893295ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-08T22:15:07.734803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.868575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:07.734855Z","caller":"traceutil/trace.go:171","msg":"trace[1030246219] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:701; }","duration":"431.937286ms","start":"2026-02-08T22:15:07.302904Z","end":"2026-02-08T22:15:07.734842Z","steps":["trace[1030246219] 'count revisions from in-memory index tree' (duration: 431.846725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:07.734885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.302902Z","time spent":"431.974027ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:07.735019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.04243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:07.735039Z","caller":"traceutil/trace.go:171","msg":"trace[563889798] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:701; }","duration":"432.06943ms","start":"2026-02-08T22:15:07.302964Z","end":"2026-02-08T22:15:07.735033Z","steps":["trace[563889798] 'range keys from in-memory index tree' (duration: 432.025939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:07.735054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.302961Z","time spent":"432.088621ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-08T22:15:07.943757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.428316ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T22:15:07.943866Z","caller":"traceutil/trace.go:171","msg":"trace[1391402476] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"232.797779ms","start":"2026-02-08T22:15:07.711056Z","end":"2026-02-08T22:15:07.943854Z","steps":["trace[1391402476] 'read index received' (duration: 73.215179ms)","trace[1391402476] 'applied index is now lower than readState.Index' (duration: 159.58166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:07.944202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.134408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:07.944226Z","caller":"traceutil/trace.go:171","msg":"trace[126534849] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"233.166499ms","start":"2026-02-08T22:15:07.711052Z","end":"2026-02-08T22:15:07.944219Z","steps":["trace[126534849] 'agreement among raft nodes before linearized reading' (duration: 233.088407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.021972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.822413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.022047Z","caller":"traceutil/trace.go:171","msg":"trace[648974879] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"310.932526ms","start":"2026-02-08T22:15:07.711101Z","end":"2026-02-08T22:15:08.022034Z","steps":["trace[648974879] 'agreement among raft nodes before linearized reading' (duration: 310.803462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.022079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.711094Z","time spent":"310.977868ms","remote":"127.0.0.1:40340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-08T22:15:08.0222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.504398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.02225Z","caller":"traceutil/trace.go:171","msg":"trace[1911313825] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"275.5719ms","start":"2026-02-08T22:15:07.746665Z","end":"2026-02-08T22:15:08.022237Z","steps":["trace[1911313825] 'agreement among raft nodes before linearized reading' (duration: 275.476968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.02226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.604931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.022287Z","caller":"traceutil/trace.go:171","msg":"trace[629586154] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"275.644381ms","start":"2026-02-08T22:15:07.746633Z","end":"2026-02-08T22:15:08.022277Z","steps":["trace[629586154] 'agreement among raft nodes before linearized reading' (duration: 275.60832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.022423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.746259ms","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-08T22:15:08.02244Z","caller":"traceutil/trace.go:171","msg":"trace[1018525686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"287.7923ms","start":"2026-02-08T22:15:07.734642Z","end":"2026-02-08T22:15:08.022435Z","steps":["trace[1018525686] 'agreement among raft nodes before linearized reading' (duration: 287.688248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.02253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.414694ms","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":"warn","ts":"2026-02-08T22:15:08.022548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.401728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.022556Z","caller":"traceutil/trace.go:171","msg":"trace[623586133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"257.464305ms","start":"2026-02-08T22:15:07.765085Z","end":"2026-02-08T22:15:08.022549Z","steps":["trace[623586133] 'agreement among raft nodes before linearized reading' (duration: 257.370843ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:08.02257Z","caller":"traceutil/trace.go:171","msg":"trace[1845064403] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"298.433969ms","start":"2026-02-08T22:15:07.72413Z","end":"2026-02-08T22:15:08.022564Z","steps":["trace[1845064403] 'agreement among raft nodes before linearized reading' (duration: 298.398488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.022679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.239457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.022698Z","caller":"traceutil/trace.go:171","msg":"trace[2136731548] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"300.267837ms","start":"2026-02-08T22:15:07.722424Z","end":"2026-02-08T22:15:08.022691Z","steps":["trace[2136731548] 'agreement among raft nodes before linearized reading' (duration: 300.236806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.022704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.30771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-08T22:15:08.022715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.72242Z","time spent":"300.290068ms","remote":"127.0.0.1:40356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-02-08T22:15:08.022728Z","caller":"traceutil/trace.go:171","msg":"trace[1403701741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:701; }","duration":"260.391392ms","start":"2026-02-08T22:15:07.76233Z","end":"2026-02-08T22:15:08.022722Z","steps":["trace[1403701741] 'agreement among raft nodes before linearized reading' (duration: 260.32441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.022798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.38159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.022818Z","caller":"traceutil/trace.go:171","msg":"trace[421091505] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"300.417511ms","start":"2026-02-08T22:15:07.722395Z","end":"2026-02-08T22:15:08.022812Z","steps":["trace[421091505] 'agreement among raft nodes before linearized reading' (duration: 300.38809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.02283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.066613ms","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-08T22:15:08.022835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.722386Z","time spent":"300.444512ms","remote":"127.0.0.1:40356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"info","ts":"2026-02-08T22:15:08.022849Z","caller":"traceutil/trace.go:171","msg":"trace[1917795490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"276.088973ms","start":"2026-02-08T22:15:07.746754Z","end":"2026-02-08T22:15:08.022843Z","steps":["trace[1917795490] 'agreement among raft nodes before linearized reading' (duration: 276.040652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.023102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.954253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:08.023121Z","caller":"traceutil/trace.go:171","msg":"trace[1883645080] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"311.987054ms","start":"2026-02-08T22:15:07.711128Z","end":"2026-02-08T22:15:08.023115Z","steps":["trace[1883645080] 'agreement among raft nodes before linearized reading' (duration: 311.957043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:08.023123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.433492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:15:08.023138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:07.711123Z","time spent":"312.009885ms","remote":"127.0.0.1:40340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-08T22:15:08.023141Z","caller":"traceutil/trace.go:171","msg":"trace[1802614734] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"276.458733ms","start":"2026-02-08T22:15:07.746677Z","end":"2026-02-08T22:15:08.023136Z","steps":["trace[1802614734] 'agreement among raft nodes before linearized reading' (duration: 276.429982ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:20.441891Z","caller":"traceutil/trace.go:171","msg":"trace[858755446] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"232.360808ms","start":"2026-02-08T22:15:20.209507Z","end":"2026-02-08T22:15:20.441868Z","steps":["trace[858755446] 'process raft request' (duration: 166.618345ms)","trace[858755446] 'compare' (duration: 65.452685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:20.921045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557470300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:15:20.934034Z","caller":"traceutil/trace.go:171","msg":"trace[1015762024] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"513.42773ms","start":"2026-02-08T22:15:20.42056Z","end":"2026-02-08T22:15:20.933988Z","steps":["trace[1015762024] 'read index received' (duration: 513.209054ms)","trace[1015762024] 'applied index is now lower than readState.Index' (duration: 217.766µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:20.934166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.582415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:20.934196Z","caller":"traceutil/trace.go:171","msg":"trace[1177650160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"513.667697ms","start":"2026-02-08T22:15:20.420519Z","end":"2026-02-08T22:15:20.934187Z","steps":["trace[1177650160] 'agreement among raft nodes before linearized reading' (duration: 513.584495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:20.934227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.420502Z","time spent":"513.717808ms","remote":"127.0.0.1:59174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:15:20.934619Z","caller":"traceutil/trace.go:171","msg":"trace[1311321252] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"722.727144ms","start":"2026-02-08T22:15:20.211879Z","end":"2026-02-08T22:15:20.934606Z","steps":["trace[1311321252] 'process raft request' (duration: 721.948254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:20.934704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.211873Z","time spent":"722.778035ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:20.934705Z","caller":"traceutil/trace.go:171","msg":"trace[494990603] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"721.82123ms","start":"2026-02-08T22:15:20.212858Z","end":"2026-02-08T22:15:20.934679Z","steps":["trace[494990603] 'process raft request' (duration: 721.093931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:20.934857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.212852Z","time spent":"721.918793ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:21.722985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.351879Z","time spent":"371.102402ms","remote":"127.0.0.1:59212","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T22:15:21.986449Z","caller":"traceutil/trace.go:171","msg":"trace[1319811133] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"162.20605ms","start":"2026-02-08T22:15:21.824101Z","end":"2026-02-08T22:15:21.986307Z","steps":["trace[1319811133] 'process raft request' (duration: 162.119357ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:21.986892Z","caller":"traceutil/trace.go:171","msg":"trace[1333672527] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"261.354217ms","start":"2026-02-08T22:15:21.725519Z","end":"2026-02-08T22:15:21.986873Z","steps":["trace[1333672527] 'process raft request' (duration: 219.619964ms)","trace[1333672527] 'compare' (duration: 40.83468ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:22.570821Z","caller":"traceutil/trace.go:171","msg":"trace[238542080] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"304.864727ms","start":"2026-02-08T22:15:22.265933Z","end":"2026-02-08T22:15:22.570798Z","steps":["trace[238542080] 'read index received' (duration: 304.58002ms)","trace[238542080] 'applied index is now lower than readState.Index' (duration: 283.797µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:22.571811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.591814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:15:22.572254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.466063ms","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-08T22:15:22.572297Z","caller":"traceutil/trace.go:171","msg":"trace[1819701900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"126.519554ms","start":"2026-02-08T22:15:22.445765Z","end":"2026-02-08T22:15:22.572284Z","steps":["trace[1819701900] 'agreement among raft nodes before linearized reading' (duration: 126.401411ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:22.572441Z","caller":"traceutil/trace.go:171","msg":"trace[594026187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"154.094158ms","start":"2026-02-08T22:15:22.418166Z","end":"2026-02-08T22:15:22.57226Z","steps":["trace[594026187] 'agreement among raft nodes before linearized reading' (duration: 153.576994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.572074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.11967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:15:22.573082Z","caller":"traceutil/trace.go:171","msg":"trace[723547642] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:748; }","duration":"307.167138ms","start":"2026-02-08T22:15:22.265899Z","end":"2026-02-08T22:15:22.573066Z","steps":["trace[723547642] 'agreement among raft nodes before linearized reading' (duration: 306.11208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.573296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.265887Z","time spent":"307.386793ms","remote":"127.0.0.1:59666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-08T22:15:22.572136Z","caller":"traceutil/trace.go:171","msg":"trace[174851355] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"326.015092ms","start":"2026-02-08T22:15:22.246108Z","end":"2026-02-08T22:15:22.572123Z","steps":["trace[174851355] 'process raft request' (duration: 324.471071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.574823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.246093Z","time spent":"328.657031ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:23.440987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557470321,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:15:23.941305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557470321,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:15:24.089287Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.511057989s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T22:15:24.089619Z","caller":"traceutil/trace.go:171","msg":"trace[726189015] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"1.14959489s","start":"2026-02-08T22:15:22.940001Z","end":"2026-02-08T22:15:24.089596Z","steps":["trace[726189015] 'read index received' (duration: 1.149415675s)","trace[726189015] 'applied index is now lower than readState.Index' (duration: 177.905µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:24.089702Z","caller":"traceutil/trace.go:171","msg":"trace[137382048] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.511588633s","start":"2026-02-08T22:15:22.578104Z","end":"2026-02-08T22:15:24.089692Z","steps":["trace[137382048] 'process raft request' (duration: 1.511372197s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.089826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.578094Z","time spent":"1.511657695s","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:24.090067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149953459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-08T22:15:24.090189Z","caller":"traceutil/trace.go:171","msg":"trace[955598648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.150145854s","start":"2026-02-08T22:15:22.940028Z","end":"2026-02-08T22:15:24.090173Z","steps":["trace[955598648] 'agreement among raft nodes before linearized reading' (duration: 1.149886967s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.090223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.939879Z","time spent":"1.150335209s","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:24.09031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.82333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:24.090409Z","caller":"traceutil/trace.go:171","msg":"trace[1869831340] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:749; }","duration":"540.894281ms","start":"2026-02-08T22:15:23.549444Z","end":"2026-02-08T22:15:24.090338Z","steps":["trace[1869831340] 'agreement among raft nodes before linearized reading' (duration: 540.830549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.090448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:23.54943Z","time spent":"541.008995ms","remote":"127.0.0.1:59512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:24.090479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.207811ms","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-08T22:15:24.090504Z","caller":"traceutil/trace.go:171","msg":"trace[1231944122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:749; }","duration":"751.265793ms","start":"2026-02-08T22:15:23.339231Z","end":"2026-02-08T22:15:24.090496Z","steps":["trace[1231944122] 'agreement among raft nodes before linearized reading' (duration: 751.107449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.090535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:23.339216Z","time spent":"751.311955ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:15:24.090686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150680258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T22:15:24.090718Z","caller":"traceutil/trace.go:171","msg":"trace[961391934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.150712569s","start":"2026-02-08T22:15:22.939996Z","end":"2026-02-08T22:15:24.090709Z","steps":["trace[961391934] 'agreement among raft nodes before linearized reading' (duration: 1.150590756s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.090744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.939955Z","time spent":"1.150783731s","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:24.626776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.010445ms","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-08T22:15:24.628062Z","caller":"traceutil/trace.go:171","msg":"trace[1126363575] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:749; }","duration":"105.302669ms","start":"2026-02-08T22:15:24.522746Z","end":"2026-02-08T22:15:24.628049Z","steps":["trace[1126363575] 'range keys from in-memory index tree' (duration: 103.997875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.627216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.759875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:24.628671Z","caller":"traceutil/trace.go:171","msg":"trace[734633103] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:780; }","duration":"338.142588ms","start":"2026-02-08T22:15:24.29052Z","end":"2026-02-08T22:15:24.628662Z","steps":["trace[734633103] 'read index received' (duration: 189.898364ms)","trace[734633103] 'applied index is now lower than readState.Index' (duration: 148.243604ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:24.628828Z","caller":"traceutil/trace.go:171","msg":"trace[1755358259] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"530.064837ms","start":"2026-02-08T22:15:24.098755Z","end":"2026-02-08T22:15:24.62882Z","steps":["trace[1755358259] 'process raft request' (duration: 529.834341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.629048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:24.098739Z","time spent":"530.18507ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:24.629425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.900478ms","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-08T22:15:24.629454Z","caller":"traceutil/trace.go:171","msg":"trace[2029985171] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:752; }","duration":"338.987461ms","start":"2026-02-08T22:15:24.290461Z","end":"2026-02-08T22:15:24.629448Z","steps":["trace[2029985171] 'agreement among raft nodes before linearized reading' (duration: 338.936769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.629473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:24.290443Z","time spent":"339.025751ms","remote":"127.0.0.1:59700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:24.629726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.218946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:24.629746Z","caller":"traceutil/trace.go:171","msg":"trace[2115993244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"208.267526ms","start":"2026-02-08T22:15:24.421474Z","end":"2026-02-08T22:15:24.629741Z","steps":["trace[2115993244] 'agreement among raft nodes before linearized reading' (duration: 208.237256ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:24.629922Z","caller":"traceutil/trace.go:171","msg":"trace[1565795695] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"530.243452ms","start":"2026-02-08T22:15:24.099125Z","end":"2026-02-08T22:15:24.629369Z","steps":["trace[1565795695] 'process raft request' (duration: 529.514112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.630128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:24.09912Z","time spent":"530.980441ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:24.628855Z","caller":"traceutil/trace.go:171","msg":"trace[702107299] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"532.504461ms","start":"2026-02-08T22:15:24.096337Z","end":"2026-02-08T22:15:24.628842Z","steps":["trace[702107299] 'process raft request' (duration: 384.070022ms)","trace[702107299] 'compare' (duration: 146.264222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:24.630476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:24.096314Z","time spent":"534.118003ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:24.853523Z","caller":"traceutil/trace.go:171","msg":"trace[628624544] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"217.038647ms","start":"2026-02-08T22:15:24.636458Z","end":"2026-02-08T22:15:24.853496Z","steps":["trace[628624544] 'process raft request' (duration: 124.131093ms)","trace[628624544] 'compare' (duration: 92.796321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:36.703903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.755104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:36.703964Z","caller":"traceutil/trace.go:171","msg":"trace[1832200947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"283.852017ms","start":"2026-02-08T22:15:36.420099Z","end":"2026-02-08T22:15:36.703951Z","steps":["trace[1832200947] 'range keys from in-memory index tree' (duration: 283.694553ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:38.307721Z","caller":"traceutil/trace.go:171","msg":"trace[1468980460] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"156.914711ms","start":"2026-02-08T22:15:38.150784Z","end":"2026-02-08T22:15:38.307698Z","steps":["trace[1468980460] 'process raft request' (duration: 156.769127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.714427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.683586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:38.714498Z","caller":"traceutil/trace.go:171","msg":"trace[968076959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"295.795599ms","start":"2026-02-08T22:15:38.418689Z","end":"2026-02-08T22:15:38.714484Z","steps":["trace[968076959] 'range keys from in-memory index tree' (duration: 295.567073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.235903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432437963557470528,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:15:39.310951Z","caller":"traceutil/trace.go:171","msg":"trace[642714199] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"576.453963ms","start":"2026-02-08T22:15:38.734483Z","end":"2026-02-08T22:15:39.310937Z","steps":["trace[642714199] 'read index received' (duration: 576.3304ms)","trace[642714199] 'applied index is now lower than readState.Index' (duration: 123.053µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:39.311115Z","caller":"traceutil/trace.go:171","msg":"trace[1752279901] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"587.262366ms","start":"2026-02-08T22:15:38.723847Z","end":"2026-02-08T22:15:39.31111Z","steps":["trace[1752279901] 'process raft request' (duration: 587.00578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.31126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.723834Z","time spent":"587.295177ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:39.312243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.755677ms","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-08T22:15:39.312263Z","caller":"traceutil/trace.go:171","msg":"trace[29365546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"577.797179ms","start":"2026-02-08T22:15:38.734459Z","end":"2026-02-08T22:15:39.312256Z","steps":["trace[29365546] 'agreement among raft nodes before linearized reading' (duration: 577.716256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.312277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.734447Z","time spent":"577.826769ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:39.312664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.182943ms","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-08T22:15:39.312681Z","caller":"traceutil/trace.go:171","msg":"trace[408745206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"561.214124ms","start":"2026-02-08T22:15:38.751462Z","end":"2026-02-08T22:15:39.312676Z","steps":["trace[408745206] 'agreement among raft nodes before linearized reading' (duration: 561.164062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.312693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.751454Z","time spent":"561.236724ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:39.313486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.84494ms","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-08T22:15:39.313508Z","caller":"traceutil/trace.go:171","msg":"trace[1119485195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"356.89094ms","start":"2026-02-08T22:15:38.95661Z","end":"2026-02-08T22:15:39.313501Z","steps":["trace[1119485195] 'agreement among raft nodes before linearized reading' (duration: 356.794638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.313522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.956597Z","time spent":"356.921732ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:40.072653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.714641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:40.072726Z","caller":"traceutil/trace.go:171","msg":"trace[992774527] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"755.36288ms","start":"2026-02-08T22:15:39.31735Z","end":"2026-02-08T22:15:40.072713Z","steps":["trace[992774527] 'process raft request' (duration: 160.536156ms)","trace[992774527] 'compare' (duration: 593.1329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:40.072764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:39.31734Z","time spent":"755.406781ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:40.111825Z","caller":"traceutil/trace.go:171","msg":"trace[558661885] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:875; }","duration":"691.186289ms","start":"2026-02-08T22:15:39.420623Z","end":"2026-02-08T22:15:40.111809Z","steps":["trace[558661885] 'read index received' (duration: 58.587925ms)","trace[558661885] 'applied index is now lower than readState.Index' (duration: 632.597774ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:40.11201Z","caller":"traceutil/trace.go:171","msg":"trace[1021777340] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"782.524722ms","start":"2026-02-08T22:15:39.329478Z","end":"2026-02-08T22:15:40.112003Z","steps":["trace[1021777340] 'process raft request' (duration: 782.229874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:40.112077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:39.329461Z","time spent":"782.564713ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:40.112171Z","caller":"traceutil/trace.go:171","msg":"trace[1002952899] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"782.631285ms","start":"2026-02-08T22:15:39.329535Z","end":"2026-02-08T22:15:40.112166Z","steps":["trace[1002952899] 'process raft request' (duration: 782.253655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:40.112196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:39.329532Z","time spent":"782.650426ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:40.112261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.643031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:40.112278Z","caller":"traceutil/trace.go:171","msg":"trace[1952235611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"691.685332ms","start":"2026-02-08T22:15:39.420587Z","end":"2026-02-08T22:15:40.112272Z","steps":["trace[1952235611] 'agreement among raft nodes before linearized reading' (duration: 691.657921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:40.11229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:39.42056Z","time spent":"691.728413ms","remote":"127.0.0.1:59174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:15:40.112387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.193701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:15:40.112401Z","caller":"traceutil/trace.go:171","msg":"trace[1438255513] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:843; }","duration":"417.233111ms","start":"2026-02-08T22:15:39.695164Z","end":"2026-02-08T22:15:40.112397Z","steps":["trace[1438255513] 'agreement among raft nodes before linearized reading' (duration: 417.20207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:40.112416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:39.695151Z","time spent":"417.262023ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:40.368314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.729252ms","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":"info","ts":"2026-02-08T22:15:40.368511Z","caller":"traceutil/trace.go:171","msg":"trace[1199002631] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:843; }","duration":"136.973789ms","start":"2026-02-08T22:15:40.231519Z","end":"2026-02-08T22:15:40.368493Z","steps":["trace[1199002631] 'count revisions from in-memory index tree' (duration: 136.659371ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:43.677978Z","caller":"traceutil/trace.go:171","msg":"trace[1883606316] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"259.464378ms","start":"2026-02-08T22:15:43.4185Z","end":"2026-02-08T22:15:43.677964Z","steps":["trace[1883606316] 'read index received' (duration: 259.341464ms)","trace[1883606316] 'applied index is now lower than readState.Index' (duration: 122.364µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:43.678065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.55072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:43.678081Z","caller":"traceutil/trace.go:171","msg":"trace[1828298768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"259.599622ms","start":"2026-02-08T22:15:43.418477Z","end":"2026-02-08T22:15:43.678076Z","steps":["trace[1828298768] 'agreement among raft nodes before linearized reading' (duration: 259.54403ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:43.678274Z","caller":"traceutil/trace.go:171","msg":"trace[1995061517] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"353.944254ms","start":"2026-02-08T22:15:43.324324Z","end":"2026-02-08T22:15:43.678269Z","steps":["trace[1995061517] 'process raft request' (duration: 353.555973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:43.67832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:43.324315Z","time spent":"353.972354ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:44.309072Z","caller":"traceutil/trace.go:171","msg":"trace[254632658] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"185.820759ms","start":"2026-02-08T22:15:44.12323Z","end":"2026-02-08T22:15:44.30905Z","steps":["trace[254632658] 'read index received' (duration: 185.597053ms)","trace[254632658] 'applied index is now lower than readState.Index' (duration: 222.746µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:44.309369Z","caller":"traceutil/trace.go:171","msg":"trace[2000770593] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"222.303884ms","start":"2026-02-08T22:15:44.087055Z","end":"2026-02-08T22:15:44.309359Z","steps":["trace[2000770593] 'process raft request' (duration: 221.831841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:44.309612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.365103ms","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-08T22:15:44.309644Z","caller":"traceutil/trace.go:171","msg":"trace[1027957965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"186.430574ms","start":"2026-02-08T22:15:44.123204Z","end":"2026-02-08T22:15:44.309635Z","steps":["trace[1027957965] 'agreement among raft nodes before linearized reading' (duration: 186.310261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:44.309888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.265982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-08T22:15:44.309994Z","caller":"traceutil/trace.go:171","msg":"trace[1934249371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"175.357944ms","start":"2026-02-08T22:15:44.134588Z","end":"2026-02-08T22:15:44.309946Z","steps":["trace[1934249371] 'agreement among raft nodes before linearized reading' (duration: 175.127348ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:44.565876Z","caller":"traceutil/trace.go:171","msg":"trace[1280317837] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:889; }","duration":"158.938604ms","start":"2026-02-08T22:15:44.406925Z","end":"2026-02-08T22:15:44.565863Z","steps":["trace[1280317837] 'read index received' (duration: 133.654422ms)","trace[1280317837] 'applied index is now lower than readState.Index' (duration: 25.283612ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:44.566022Z","caller":"traceutil/trace.go:171","msg":"trace[1660857935] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"246.408256ms","start":"2026-02-08T22:15:44.319606Z","end":"2026-02-08T22:15:44.566014Z","steps":["trace[1660857935] 'process raft request' (duration: 220.999091ms)","trace[1660857935] 'compare' (duration: 25.170379ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:44.566109Z","caller":"traceutil/trace.go:171","msg":"trace[441726276] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"246.316063ms","start":"2026-02-08T22:15:44.319789Z","end":"2026-02-08T22:15:44.566105Z","steps":["trace[441726276] 'process raft request' (duration: 246.053737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:44.566214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.285843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T22:15:44.56624Z","caller":"traceutil/trace.go:171","msg":"trace[867477899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:855; }","duration":"159.345915ms","start":"2026-02-08T22:15:44.406889Z","end":"2026-02-08T22:15:44.566235Z","steps":["trace[867477899] 'agreement among raft nodes before linearized reading' (duration: 159.265323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:44.566312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.443573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:44.566324Z","caller":"traceutil/trace.go:171","msg":"trace[1512586730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"147.485124ms","start":"2026-02-08T22:15:44.418835Z","end":"2026-02-08T22:15:44.56632Z","steps":["trace[1512586730] 'agreement among raft nodes before linearized reading' (duration: 147.466494ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:50.61814Z","caller":"traceutil/trace.go:171","msg":"trace[361834970] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"221.329749ms","start":"2026-02-08T22:15:50.396792Z","end":"2026-02-08T22:15:50.618121Z","steps":["trace[361834970] 'read index received' (duration: 221.174105ms)","trace[361834970] 'applied index is now lower than readState.Index' (duration: 154.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:50.618928Z","caller":"traceutil/trace.go:171","msg":"trace[1457962768] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"281.6918ms","start":"2026-02-08T22:15:50.337221Z","end":"2026-02-08T22:15:50.618913Z","steps":["trace[1457962768] 'process raft request' (duration: 280.796207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:50.619142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.331245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:15:50.619179Z","caller":"traceutil/trace.go:171","msg":"trace[1561589941] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:869; }","duration":"222.405327ms","start":"2026-02-08T22:15:50.396761Z","end":"2026-02-08T22:15:50.619167Z","steps":["trace[1561589941] 'agreement among raft nodes before linearized reading' (duration: 222.334415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:50.61934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.280059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:50.61937Z","caller":"traceutil/trace.go:171","msg":"trace[467497340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"202.329001ms","start":"2026-02-08T22:15:50.41703Z","end":"2026-02-08T22:15:50.619359Z","steps":["trace[467497340] 'agreement among raft nodes before linearized reading' (duration: 202.28637ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:53.154005Z","caller":"traceutil/trace.go:171","msg":"trace[39975029] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"247.3524ms","start":"2026-02-08T22:15:52.906637Z","end":"2026-02-08T22:15:53.153989Z","steps":["trace[39975029] 'process raft request' (duration: 247.234186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:53.489015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.687866ms","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-08T22:15:53.489098Z","caller":"traceutil/trace.go:171","msg":"trace[971830644] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:879; }","duration":"132.8157ms","start":"2026-02-08T22:15:53.356267Z","end":"2026-02-08T22:15:53.489083Z","steps":["trace[971830644] 'count revisions from in-memory index tree' (duration: 132.608264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:54.937705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.019373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:54.937865Z","caller":"traceutil/trace.go:171","msg":"trace[8524209] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"256.209312ms","start":"2026-02-08T22:15:54.681634Z","end":"2026-02-08T22:15:54.937843Z","steps":["trace[8524209] 'process raft request' (duration: 140.938912ms)","trace[8524209] 'compare' (duration: 114.185882ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:55.137339Z","caller":"traceutil/trace.go:171","msg":"trace[1948745102] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:919; }","duration":"444.219549ms","start":"2026-02-08T22:15:54.693092Z","end":"2026-02-08T22:15:55.137312Z","steps":["trace[1948745102] 'read index received' (duration: 129.498493ms)","trace[1948745102] 'applied index is now lower than readState.Index' (duration: 314.720126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:55.137526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.410954ms","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-08T22:15:55.137559Z","caller":"traceutil/trace.go:171","msg":"trace[1846290956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"444.474766ms","start":"2026-02-08T22:15:54.693072Z","end":"2026-02-08T22:15:55.137547Z","steps":["trace[1846290956] 'agreement among raft nodes before linearized reading' (duration: 444.333672ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:55.137543Z","caller":"traceutil/trace.go:171","msg":"trace[2073752323] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"445.333178ms","start":"2026-02-08T22:15:54.692182Z","end":"2026-02-08T22:15:55.137515Z","steps":["trace[2073752323] 'process raft request' (duration: 350.273207ms)","trace[2073752323] 'compare' (duration: 94.742813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:55.137599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:54.693064Z","time spent":"444.527287ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:55.137678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:54.692168Z","time spent":"445.43436ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:55.300267Z","caller":"traceutil/trace.go:171","msg":"trace[2089126272] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"155.535435ms","start":"2026-02-08T22:15:55.144708Z","end":"2026-02-08T22:15:55.300243Z","steps":["trace[2089126272] 'process raft request' (duration: 108.949675ms)","trace[2089126272] 'compare' (duration: 46.025145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:55.623218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.867109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:55.623297Z","caller":"traceutil/trace.go:171","msg":"trace[39532899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"201.986732ms","start":"2026-02-08T22:15:55.421291Z","end":"2026-02-08T22:15:55.623278Z","steps":["trace[39532899] 'range keys from in-memory index tree' (duration: 201.802067ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:55.857262Z","caller":"traceutil/trace.go:171","msg":"trace[252116538] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"114.178681ms","start":"2026-02-08T22:15:55.74306Z","end":"2026-02-08T22:15:55.857239Z","steps":["trace[252116538] 'process raft request' (duration: 113.421821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:56.451726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.069369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:56.452133Z","caller":"traceutil/trace.go:171","msg":"trace[1856513811] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"589.793492ms","start":"2026-02-08T22:15:55.862321Z","end":"2026-02-08T22:15:56.452114Z","steps":["trace[1856513811] 'process raft request' (duration: 112.269941ms)","trace[1856513811] 'compare' (duration: 476.513275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:56.452197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:55.8623Z","time spent":"589.866284ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:59.491807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.678319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:59.4919Z","caller":"traceutil/trace.go:171","msg":"trace[1164588944] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"803.917342ms","start":"2026-02-08T22:15:58.687967Z","end":"2026-02-08T22:15:59.491884Z","steps":["trace[1164588944] 'read index received' (duration: 248.010328ms)","trace[1164588944] 'applied index is now lower than readState.Index' (duration: 555.905994ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:59.492121Z","caller":"traceutil/trace.go:171","msg":"trace[722536935] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.026189915s","start":"2026-02-08T22:15:58.465919Z","end":"2026-02-08T22:15:59.492109Z","steps":["trace[722536935] 'process raft request' (duration: 470.118427ms)","trace[722536935] 'compare' (duration: 555.398561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:59.492179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:58.465899Z","time spent":"1.026249787s","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:59.492406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.434206ms","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-08T22:15:59.492437Z","caller":"traceutil/trace.go:171","msg":"trace[1495104558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"804.500758ms","start":"2026-02-08T22:15:58.687925Z","end":"2026-02-08T22:15:59.492426Z","steps":["trace[1495104558] 'agreement among raft nodes before linearized reading' (duration: 804.393805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.492468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:58.687909Z","time spent":"804.548759ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:59.492635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.35889ms","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-08T22:15:59.492661Z","caller":"traceutil/trace.go:171","msg":"trace[25626081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"534.408082ms","start":"2026-02-08T22:15:58.958245Z","end":"2026-02-08T22:15:59.492653Z","steps":["trace[25626081] 'agreement among raft nodes before linearized reading' (duration: 534.33188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.492687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:58.958232Z","time spent":"534.449602ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:59.492899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.719147ms","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-08T22:15:59.492925Z","caller":"traceutil/trace.go:171","msg":"trace[830381651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"182.775009ms","start":"2026-02-08T22:15:59.310142Z","end":"2026-02-08T22:15:59.492917Z","steps":["trace[830381651] 'agreement among raft nodes before linearized reading' (duration: 182.706807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.493039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.390391ms","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-08T22:15:59.493061Z","caller":"traceutil/trace.go:171","msg":"trace[136164733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"342.458602ms","start":"2026-02-08T22:15:59.150596Z","end":"2026-02-08T22:15:59.493054Z","steps":["trace[136164733] 'agreement among raft nodes before linearized reading' (duration: 342.39415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.493084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.150579Z","time spent":"342.498954ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T22:15:59.875642Z","caller":"traceutil/trace.go:171","msg":"trace[641835867] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"374.228084ms","start":"2026-02-08T22:15:59.501391Z","end":"2026-02-08T22:15:59.875619Z","steps":["trace[641835867] 'read index received' (duration: 334.062292ms)","trace[641835867] 'applied index is now lower than readState.Index' (duration: 40.164872ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:59.875792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.381478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:59.875815Z","caller":"traceutil/trace.go:171","msg":"trace[1577666815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"374.419859ms","start":"2026-02-08T22:15:59.501389Z","end":"2026-02-08T22:15:59.875809Z","steps":["trace[1577666815] 'agreement among raft nodes before linearized reading' (duration: 374.309496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.875839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.501372Z","time spent":"374.462151ms","remote":"127.0.0.1:59186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:15:59.87597Z","caller":"traceutil/trace.go:171","msg":"trace[813514360] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"374.754159ms","start":"2026-02-08T22:15:59.501178Z","end":"2026-02-08T22:15:59.875932Z","steps":["trace[813514360] 'process raft request' (duration: 334.3433ms)","trace[813514360] 'compare' (duration: 39.979847ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:59.87609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.501163Z","time spent":"374.868781ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:59.879972Z","caller":"traceutil/trace.go:171","msg":"trace[330303730] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"375.665782ms","start":"2026-02-08T22:15:59.504294Z","end":"2026-02-08T22:15:59.87996Z","steps":["trace[330303730] 'process raft request' (duration: 375.624031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.880057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.504288Z","time spent":"375.719124ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:59.880268Z","caller":"traceutil/trace.go:171","msg":"trace[1789774893] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"377.585703ms","start":"2026-02-08T22:15:59.502675Z","end":"2026-02-08T22:15:59.880261Z","steps":["trace[1789774893] 'process raft request' (duration: 377.154052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.880307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.502669Z","time spent":"377.617024ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:59.880385Z","caller":"traceutil/trace.go:171","msg":"trace[239931875] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"377.011908ms","start":"2026-02-08T22:15:59.503367Z","end":"2026-02-08T22:15:59.880379Z","steps":["trace[239931875] 'process raft request' (duration: 376.529265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.880417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.50336Z","time spent":"377.038458ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:16:03.259016Z","caller":"traceutil/trace.go:171","msg":"trace[1328128943] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"263.400892ms","start":"2026-02-08T22:16:02.995596Z","end":"2026-02-08T22:16:03.258997Z","steps":["trace[1328128943] 'process raft request' (duration: 263.34964ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:03.259278Z","caller":"traceutil/trace.go:171","msg":"trace[1445363857] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"264.805058ms","start":"2026-02-08T22:16:02.994466Z","end":"2026-02-08T22:16:03.259271Z","steps":["trace[1445363857] 'process raft request' (duration: 261.695526ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:16.324811Z","caller":"traceutil/trace.go:171","msg":"trace[2012559584] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"252.65241ms","start":"2026-02-08T22:16:16.072139Z","end":"2026-02-08T22:16:16.324791Z","steps":["trace[2012559584] 'process raft request' (duration: 214.052012ms)","trace[2012559584] 'compare' (duration: 38.332761ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:16:18.281466Z","caller":"traceutil/trace.go:171","msg":"trace[645169967] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"577.822695ms","start":"2026-02-08T22:16:17.703626Z","end":"2026-02-08T22:16:18.281449Z","steps":["trace[645169967] 'process raft request' (duration: 577.680591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:18.281769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:17.703614Z","time spent":"578.097133ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:16:18.460271Z","caller":"traceutil/trace.go:171","msg":"trace[1600783524] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"516.594476ms","start":"2026-02-08T22:16:17.94366Z","end":"2026-02-08T22:16:18.460255Z","steps":["trace[1600783524] 'read index received' (duration: 338.246757ms)","trace[1600783524] 'applied index is now lower than readState.Index' (duration: 178.346889ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:16:18.460599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.918454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T22:16:18.460644Z","caller":"traceutil/trace.go:171","msg":"trace[624914192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"517.034567ms","start":"2026-02-08T22:16:17.943598Z","end":"2026-02-08T22:16:18.460633Z","steps":["trace[624914192] 'agreement among raft nodes before linearized reading' (duration: 516.730859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:18.460674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:17.943581Z","time spent":"517.083699ms","remote":"127.0.0.1:59474","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-08T22:16:18.461099Z","caller":"traceutil/trace.go:171","msg":"trace[367645961] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"757.385906ms","start":"2026-02-08T22:16:17.703704Z","end":"2026-02-08T22:16:18.46109Z","steps":["trace[367645961] 'process raft request' (duration: 756.177654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:18.461467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:17.703699Z","time spent":"757.426177ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:16:18.465791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.961214ms","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-08T22:16:18.465834Z","caller":"traceutil/trace.go:171","msg":"trace[755848489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"494.030095ms","start":"2026-02-08T22:16:17.971796Z","end":"2026-02-08T22:16:18.465826Z","steps":["trace[755848489] 'agreement among raft nodes before linearized reading' (duration: 493.937453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:18.465855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:17.971786Z","time spent":"494.063447ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:16:18.466103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.239417ms","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-08T22:16:18.466131Z","caller":"traceutil/trace.go:171","msg":"trace[375178042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"482.31808ms","start":"2026-02-08T22:16:17.983806Z","end":"2026-02-08T22:16:18.466124Z","steps":["trace[375178042] 'agreement among raft nodes before linearized reading' (duration: 482.248948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:18.466149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:17.983794Z","time spent":"482.351291ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:16:18.466548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.389428ms","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-08T22:16:18.466579Z","caller":"traceutil/trace.go:171","msg":"trace[577615349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"483.46441ms","start":"2026-02-08T22:16:17.983108Z","end":"2026-02-08T22:16:18.466572Z","steps":["trace[577615349] 'agreement among raft nodes before linearized reading' (duration: 483.350867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:18.466598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:17.983085Z","time spent":"483.50885ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}