{"level":"warn","ts":"2026-02-08T22:14:22.122755Z","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:14:22.122913Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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:14:22.123063Z","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:14:22.12309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-08T22:14:22.123134Z","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:14:22.124341Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-08T22:14:22.124566Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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:14:22.131547Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.555226ms"} {"level":"info","ts":"2026-02-08T22:14:22.14179Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-08T22:14:22.141831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:14:22.141848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-08T22:14:22.141855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:14:22.141862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-08T22:14:22.141889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-08T22:14:22.151064Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:14:22.156162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:14:22.16061Z","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:14:22.163525Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:14:22.165821Z","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:14:22.166319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:14:22.166374Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:14:22.169043Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-08T22:14:22.170333Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-08T22:14:22.170038Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:14:22.170095Z","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:14:22.17056Z","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:14:22.170579Z","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:14:22.170262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-08T22:14:22.170786Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-08T22:14:22.643091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:14:22.643268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:14:22.643349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-08T22:14:22.643392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:14:22.643449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-08T22:14:22.643498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-08T22:14:22.643534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-08T22:14:22.647842Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:14:22.648017Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:14:22.648125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:14:22.648464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:14:22.650041Z","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:14:22.65133Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:14:22.65136Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:14:22.657305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:14:22.657395Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:14:22.658286Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:14:22.674176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"warn","ts":"2026-02-08T22:14:29.481841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.071264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:29.481959Z","caller":"traceutil/trace.go:171","msg":"trace[1205135562] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:280; }","duration":"116.2811ms","start":"2026-02-08T22:14:29.365653Z","end":"2026-02-08T22:14:29.481934Z","steps":["trace[1205135562] 'range keys from in-memory index tree' (duration: 115.9589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:29.682539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.767922ms","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:29.682645Z","caller":"traceutil/trace.go:171","msg":"trace[1160249322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:281; }","duration":"100.900715ms","start":"2026-02-08T22:14:29.58173Z","end":"2026-02-08T22:14:29.682631Z","steps":["trace[1160249322] 'range keys from in-memory index tree' (duration: 100.493313ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:38.173285Z","caller":"traceutil/trace.go:171","msg":"trace[1710243202] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"377.278724ms","start":"2026-02-08T22:14:37.795979Z","end":"2026-02-08T22:14:38.173258Z","steps":["trace[1710243202] 'read index received' (duration: 377.12442ms)","trace[1710243202] 'applied index is now lower than readState.Index' (duration: 153.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:38.173395Z","caller":"traceutil/trace.go:171","msg":"trace[1678911010] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"969.698552ms","start":"2026-02-08T22:14:37.203689Z","end":"2026-02-08T22:14:38.173387Z","steps":["trace[1678911010] 'process raft request' (duration: 969.411542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:38.173728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.726188ms","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.173807Z","caller":"traceutil/trace.go:171","msg":"trace[1746673752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"377.867153ms","start":"2026-02-08T22:14:37.79592Z","end":"2026-02-08T22:14:38.173788Z","steps":["trace[1746673752] 'agreement among raft nodes before linearized reading' (duration: 377.734409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:38.173843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:37.795895Z","time spent":"377.940206ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:14:38.173868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:37.203662Z","time spent":"969.762103ms","remote":"127.0.0.1:35876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4108,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:39.167392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.228532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:39.167564Z","caller":"traceutil/trace.go:171","msg":"trace[2059931752] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"776.768459ms","start":"2026-02-08T22:14:38.390781Z","end":"2026-02-08T22:14:39.167549Z","steps":["trace[2059931752] 'process raft request' (duration: 776.743318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.167603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.390746Z","time spent":"776.840281ms","remote":"127.0.0.1:35876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4048,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:39.167784Z","caller":"traceutil/trace.go:171","msg":"trace[912956363] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.259980638s","start":"2026-02-08T22:14:37.907797Z","end":"2026-02-08T22:14:39.167778Z","steps":["trace[912956363] 'process raft request' (duration: 644.218367ms)","trace[912956363] 'compare' (duration: 613.610953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:39.167819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:37.907774Z","time spent":"1.260022409s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:39.167898Z","caller":"traceutil/trace.go:171","msg":"trace[1543279983] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"994.525002ms","start":"2026-02-08T22:14:38.17336Z","end":"2026-02-08T22:14:39.167885Z","steps":["trace[1543279983] 'read index received' (duration: 378.663728ms)","trace[1543279983] 'applied index is now lower than readState.Index' (duration: 615.860604ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:39.167935Z","caller":"traceutil/trace.go:171","msg":"trace[832755359] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"984.838658ms","start":"2026-02-08T22:14:38.183093Z","end":"2026-02-08T22:14:39.167931Z","steps":["trace[832755359] 'process raft request' (duration: 984.390254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.167957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.183021Z","time spent":"984.92334ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:39.168092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277798098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4730"} {"level":"info","ts":"2026-02-08T22:14:39.168111Z","caller":"traceutil/trace.go:171","msg":"trace[1567794728] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:370; }","duration":"1.277871741s","start":"2026-02-08T22:14:37.890234Z","end":"2026-02-08T22:14:39.168106Z","steps":["trace[1567794728] 'agreement among raft nodes before linearized reading' (duration: 1.27782512s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.168128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:37.890209Z","time spent":"1.277914503s","remote":"127.0.0.1:35886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4754,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-08T22:14:39.168212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.026676ms","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:14:39.168224Z","caller":"traceutil/trace.go:171","msg":"trace[158030096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"891.090757ms","start":"2026-02-08T22:14:38.27713Z","end":"2026-02-08T22:14:39.168221Z","steps":["trace[158030096] 'agreement among raft nodes before linearized reading' (duration: 891.067566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.168235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.277105Z","time spent":"891.127548ms","remote":"127.0.0.1:35966","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:39.170655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.664492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-08T22:14:39.170676Z","caller":"traceutil/trace.go:171","msg":"trace[337076453] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"333.686923ms","start":"2026-02-08T22:14:38.836982Z","end":"2026-02-08T22:14:39.170669Z","steps":["trace[337076453] 'agreement among raft nodes before linearized reading' (duration: 333.646182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.170691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.836883Z","time spent":"333.804687ms","remote":"127.0.0.1:35876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4086,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-08T22:14:39.170803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.576524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T22:14:39.170817Z","caller":"traceutil/trace.go:171","msg":"trace[1740882892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"614.619046ms","start":"2026-02-08T22:14:38.556194Z","end":"2026-02-08T22:14:39.170813Z","steps":["trace[1740882892] 'agreement among raft nodes before linearized reading' (duration: 614.589755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.170839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.556182Z","time spent":"614.654966ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:14:39.170936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.413273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T22:14:39.17095Z","caller":"traceutil/trace.go:171","msg":"trace[1894919380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"644.427784ms","start":"2026-02-08T22:14:38.526518Z","end":"2026-02-08T22:14:39.170946Z","steps":["trace[1894919380] 'agreement among raft nodes before linearized reading' (duration: 644.400993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.170962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:38.526447Z","time spent":"644.512536ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:14:39.37862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.4338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:39.378719Z","caller":"traceutil/trace.go:171","msg":"trace[1586901342] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"199.796208ms","start":"2026-02-08T22:14:39.178904Z","end":"2026-02-08T22:14:39.378701Z","steps":["trace[1586901342] 'process raft request' (duration: 99.209733ms)","trace[1586901342] 'compare' (duration: 100.255465ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:39.381286Z","caller":"traceutil/trace.go:171","msg":"trace[453681768] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"199.140327ms","start":"2026-02-08T22:14:39.182136Z","end":"2026-02-08T22:14:39.381276Z","steps":["trace[453681768] 'process raft request' (duration: 198.834398ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:39.381259Z","caller":"traceutil/trace.go:171","msg":"trace[766710577] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"198.997523ms","start":"2026-02-08T22:14:39.182226Z","end":"2026-02-08T22:14:39.381224Z","steps":["trace[766710577] 'process raft request' (duration: 198.952081ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:39.381435Z","caller":"traceutil/trace.go:171","msg":"trace[1601980289] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"199.257101ms","start":"2026-02-08T22:14:39.182173Z","end":"2026-02-08T22:14:39.38143Z","steps":["trace[1601980289] 'process raft request' (duration: 198.954622ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:43.519411Z","caller":"traceutil/trace.go:171","msg":"trace[1347286105] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"119.601413ms","start":"2026-02-08T22:14:43.39978Z","end":"2026-02-08T22:14:43.519381Z","steps":["trace[1347286105] 'read index received' (duration: 119.384926ms)","trace[1347286105] 'applied index is now lower than readState.Index' (duration: 215.117µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:43.51957Z","caller":"traceutil/trace.go:171","msg":"trace[1375802426] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"122.329139ms","start":"2026-02-08T22:14:43.39723Z","end":"2026-02-08T22:14:43.519559Z","steps":["trace[1375802426] 'process raft request' (duration: 121.965917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:43.519664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.997774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T22:14:43.51974Z","caller":"traceutil/trace.go:171","msg":"trace[1109160468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"119.095458ms","start":"2026-02-08T22:14:43.400623Z","end":"2026-02-08T22:14:43.519718Z","steps":["trace[1109160468] 'agreement among raft nodes before linearized reading' (duration: 118.979514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:43.519855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.082989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T22:14:43.519874Z","caller":"traceutil/trace.go:171","msg":"trace[258763751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"120.135541ms","start":"2026-02-08T22:14:43.399731Z","end":"2026-02-08T22:14:43.519867Z","steps":["trace[258763751] 'agreement among raft nodes before linearized reading' (duration: 120.1068ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:44.141748Z","caller":"traceutil/trace.go:171","msg":"trace[1069251254] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"615.041549ms","start":"2026-02-08T22:14:43.526658Z","end":"2026-02-08T22:14:44.141699Z","steps":["trace[1069251254] 'process raft request' (duration: 551.123757ms)","trace[1069251254] 'compare' (duration: 61.94688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:44.142067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:43.52665Z","time spent":"615.171832ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:44.144437Z","caller":"traceutil/trace.go:171","msg":"trace[140802792] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"617.791255ms","start":"2026-02-08T22:14:43.526616Z","end":"2026-02-08T22:14:44.144407Z","steps":["trace[140802792] 'process raft request' (duration: 617.6386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:44.145432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:43.526593Z","time spent":"618.796237ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:51.66385Z","caller":"traceutil/trace.go:171","msg":"trace[388690857] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"114.745032ms","start":"2026-02-08T22:14:51.549078Z","end":"2026-02-08T22:14:51.663823Z","steps":["trace[388690857] 'read index received' (duration: 17.399628ms)","trace[388690857] 'applied index is now lower than readState.Index' (duration: 97.344364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:51.664276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.164695ms","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:51.664325Z","caller":"traceutil/trace.go:171","msg":"trace[2108794891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:438; }","duration":"115.252747ms","start":"2026-02-08T22:14:51.549061Z","end":"2026-02-08T22:14:51.664314Z","steps":["trace[2108794891] 'agreement among raft nodes before linearized reading' (duration: 114.867395ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:51.667896Z","caller":"traceutil/trace.go:171","msg":"trace[1325930343] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"122.375122ms","start":"2026-02-08T22:14:51.545502Z","end":"2026-02-08T22:14:51.667878Z","steps":["trace[1325930343] 'process raft request' (duration: 82.155386ms)","trace[1325930343] 'compare' (duration: 36.066465ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:51.843658Z","caller":"traceutil/trace.go:171","msg":"trace[566809461] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"134.388399ms","start":"2026-02-08T22:14:51.709046Z","end":"2026-02-08T22:14:51.843434Z","steps":["trace[566809461] 'process raft request' (duration: 77.119837ms)","trace[566809461] 'compare' (duration: 57.126678ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:57.190952Z","caller":"traceutil/trace.go:171","msg":"trace[1804136940] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"229.419791ms","start":"2026-02-08T22:14:56.961497Z","end":"2026-02-08T22:14:57.190917Z","steps":["trace[1804136940] 'process raft request' (duration: 228.846423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.966751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.016399ms","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:14:57.966888Z","caller":"traceutil/trace.go:171","msg":"trace[1301273433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"691.214085ms","start":"2026-02-08T22:14:57.27565Z","end":"2026-02-08T22:14:57.966864Z","steps":["trace[1301273433] 'range keys from in-memory index tree' (duration: 690.827232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.966943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.275627Z","time spent":"691.303688ms","remote":"127.0.0.1:35966","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:57.999308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085533020471652,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:14:58.280816Z","caller":"traceutil/trace.go:171","msg":"trace[1137479265] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"975.960317ms","start":"2026-02-08T22:14:57.304822Z","end":"2026-02-08T22:14:58.280783Z","steps":["trace[1137479265] 'process raft request' (duration: 975.73213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.281242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.3048Z","time spent":"976.188705ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:14:58.311778Z","caller":"traceutil/trace.go:171","msg":"trace[1505227927] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"813.492213ms","start":"2026-02-08T22:14:57.49827Z","end":"2026-02-08T22:14:58.311762Z","steps":["trace[1505227927] 'read index received' (duration: 782.528409ms)","trace[1505227927] 'applied index is now lower than readState.Index' (duration: 30.963244ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:58.314971Z","caller":"traceutil/trace.go:171","msg":"trace[1865831112] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"605.997823ms","start":"2026-02-08T22:14:57.70896Z","end":"2026-02-08T22:14:58.314958Z","steps":["trace[1865831112] 'process raft request' (duration: 599.697024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.315045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.708926Z","time spent":"606.083295ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:14:58.315207Z","caller":"traceutil/trace.go:171","msg":"trace[1061837629] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"324.0976ms","start":"2026-02-08T22:14:57.991104Z","end":"2026-02-08T22:14:58.315202Z","steps":["trace[1061837629] 'process raft request' (duration: 320.532708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.315242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.991074Z","time spent":"324.146972ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:58.315305Z","caller":"traceutil/trace.go:171","msg":"trace[1196810762] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"178.069195ms","start":"2026-02-08T22:14:58.137232Z","end":"2026-02-08T22:14:58.315301Z","steps":["trace[1196810762] 'process raft request' (duration: 174.485142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.315387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.117897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-08T22:14:58.315406Z","caller":"traceutil/trace.go:171","msg":"trace[1031570728] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"817.17647ms","start":"2026-02-08T22:14:57.498224Z","end":"2026-02-08T22:14:58.3154Z","steps":["trace[1031570728] 'agreement among raft nodes before linearized reading' (duration: 817.114898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.315419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.498202Z","time spent":"817.21394ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-08T22:14:58.319139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.463952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-08T22:14:58.319182Z","caller":"traceutil/trace.go:171","msg":"trace[1321765134] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"419.579216ms","start":"2026-02-08T22:14:57.899592Z","end":"2026-02-08T22:14:58.319171Z","steps":["trace[1321765134] 'agreement among raft nodes before linearized reading' (duration: 419.444342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.319205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.89956Z","time spent":"419.641168ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-08T22:14:58.319278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.025524ms","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:58.319293Z","caller":"traceutil/trace.go:171","msg":"trace[1161245590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"545.090076ms","start":"2026-02-08T22:14:57.774199Z","end":"2026-02-08T22:14:58.319289Z","steps":["trace[1161245590] 'agreement among raft nodes before linearized reading' (duration: 545.049275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.319314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.774172Z","time spent":"545.136797ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T22:14:58.891356Z","caller":"traceutil/trace.go:171","msg":"trace[1456458974] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"353.688223ms","start":"2026-02-08T22:14:58.537654Z","end":"2026-02-08T22:14:58.891342Z","steps":["trace[1456458974] 'process raft request' (duration: 353.58818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:58.892663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:58.537637Z","time spent":"354.946482ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:59.159427Z","caller":"traceutil/trace.go:171","msg":"trace[479660317] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:544; }","duration":"461.35092ms","start":"2026-02-08T22:14:58.698058Z","end":"2026-02-08T22:14:59.159409Z","steps":["trace[479660317] 'read index received' (duration: 205.143057ms)","trace[479660317] 'applied index is now lower than readState.Index' (duration: 256.207033ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:59.159959Z","caller":"traceutil/trace.go:171","msg":"trace[1641216963] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"613.382185ms","start":"2026-02-08T22:14:58.546565Z","end":"2026-02-08T22:14:59.159947Z","steps":["trace[1641216963] 'process raft request' (duration: 612.498787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:59.160052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:58.546555Z","time spent":"613.452697ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:14:59.160182Z","caller":"traceutil/trace.go:171","msg":"trace[2041265073] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"586.901382ms","start":"2026-02-08T22:14:58.573274Z","end":"2026-02-08T22:14:59.160175Z","steps":["trace[2041265073] 'process raft request' (duration: 585.999994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:59.160228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:58.573244Z","time spent":"586.957073ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:59.160365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.30507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-08T22:14:59.160393Z","caller":"traceutil/trace.go:171","msg":"trace[931564755] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:525; }","duration":"462.365992ms","start":"2026-02-08T22:14:58.698018Z","end":"2026-02-08T22:14:59.160384Z","steps":["trace[931564755] 'agreement among raft nodes before linearized reading' (duration: 462.28662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:59.160415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:58.698006Z","time spent":"462.404894ms","remote":"127.0.0.1:35742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-08T22:14:59.16946Z","caller":"traceutil/trace.go:171","msg":"trace[2018869364] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"261.592683ms","start":"2026-02-08T22:14:58.907851Z","end":"2026-02-08T22:14:59.169443Z","steps":["trace[2018869364] 'process raft request' (duration: 261.452099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:59.169754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.303791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-08T22:14:59.169775Z","caller":"traceutil/trace.go:171","msg":"trace[66141785] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:526; }","duration":"266.361423ms","start":"2026-02-08T22:14:58.903406Z","end":"2026-02-08T22:14:59.169768Z","steps":["trace[66141785] 'agreement among raft nodes before linearized reading' (duration: 265.984001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.646248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.14135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:00.646336Z","caller":"traceutil/trace.go:171","msg":"trace[1775795330] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"250.438492ms","start":"2026-02-08T22:15:00.39588Z","end":"2026-02-08T22:15:00.646319Z","steps":["trace[1775795330] 'process raft request' (duration: 104.178249ms)","trace[1775795330] 'compare' (duration: 145.878721ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:00.65129Z","caller":"traceutil/trace.go:171","msg":"trace[2033951429] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"236.608727ms","start":"2026-02-08T22:15:00.414672Z","end":"2026-02-08T22:15:00.651281Z","steps":["trace[2033951429] 'read index received' (duration: 85.395748ms)","trace[2033951429] 'applied index is now lower than readState.Index' (duration: 151.211039ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:00.65135Z","caller":"traceutil/trace.go:171","msg":"trace[2025623200] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"236.843784ms","start":"2026-02-08T22:15:00.414502Z","end":"2026-02-08T22:15:00.651346Z","steps":["trace[2025623200] 'process raft request' (duration: 236.71352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.752292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.651452Z","caller":"traceutil/trace.go:171","msg":"trace[200569441] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:535; }","duration":"236.791803ms","start":"2026-02-08T22:15:00.414654Z","end":"2026-02-08T22:15:00.651446Z","steps":["trace[200569441] 'agreement among raft nodes before linearized reading' (duration: 236.754701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.866055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.651586Z","caller":"traceutil/trace.go:171","msg":"trace[788094786] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:535; }","duration":"236.892066ms","start":"2026-02-08T22:15:00.41469Z","end":"2026-02-08T22:15:00.651582Z","steps":["trace[788094786] 'agreement among raft nodes before linearized reading' (duration: 236.864545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.279875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:15:00.651657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.318816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.651671Z","caller":"traceutil/trace.go:171","msg":"trace[1995335270] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:535; }","duration":"140.340817ms","start":"2026-02-08T22:15:00.511326Z","end":"2026-02-08T22:15:00.651667Z","steps":["trace[1995335270] 'agreement among raft nodes before linearized reading' (duration: 140.316906ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:00.651675Z","caller":"traceutil/trace.go:171","msg":"trace[1305249105] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:535; }","duration":"140.326986ms","start":"2026-02-08T22:15:00.511337Z","end":"2026-02-08T22:15:00.651664Z","steps":["trace[1305249105] 'agreement among raft nodes before linearized reading' (duration: 140.267505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.42682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.651759Z","caller":"traceutil/trace.go:171","msg":"trace[1606294916] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:535; }","duration":"140.45995ms","start":"2026-02-08T22:15:00.511296Z","end":"2026-02-08T22:15:00.651756Z","steps":["trace[1606294916] 'agreement among raft nodes before linearized reading' (duration: 140.43949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.335674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T22:15:00.651867Z","caller":"traceutil/trace.go:171","msg":"trace[1128024781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"145.365365ms","start":"2026-02-08T22:15:00.506498Z","end":"2026-02-08T22:15:00.651864Z","steps":["trace[1128024781] 'agreement among raft nodes before linearized reading' (duration: 145.302493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.460848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T22:15:00.651964Z","caller":"traceutil/trace.go:171","msg":"trace[1235386277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"145.473439ms","start":"2026-02-08T22:15:00.506487Z","end":"2026-02-08T22:15:00.651961Z","steps":["trace[1235386277] 'agreement among raft nodes before linearized reading' (duration: 145.424607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.651958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.322397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.651988Z","caller":"traceutil/trace.go:171","msg":"trace[384426939] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:535; }","duration":"134.355738ms","start":"2026-02-08T22:15:00.517624Z","end":"2026-02-08T22:15:00.65198Z","steps":["trace[384426939] 'agreement among raft nodes before linearized reading' (duration: 134.296987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.652031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.316949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.652052Z","caller":"traceutil/trace.go:171","msg":"trace[187234545] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:535; }","duration":"237.343841ms","start":"2026-02-08T22:15:00.414705Z","end":"2026-02-08T22:15:00.652048Z","steps":["trace[187234545] 'agreement among raft nodes before linearized reading' (duration: 237.31481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.652158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.546525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.652186Z","caller":"traceutil/trace.go:171","msg":"trace[935866507] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:535; }","duration":"134.585016ms","start":"2026-02-08T22:15:00.517594Z","end":"2026-02-08T22:15:00.652179Z","steps":["trace[935866507] 'agreement among raft nodes before linearized reading' (duration: 134.541594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:00.652304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.833163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:00.652332Z","caller":"traceutil/trace.go:171","msg":"trace[1788973386] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:535; }","duration":"134.869325ms","start":"2026-02-08T22:15:00.517458Z","end":"2026-02-08T22:15:00.652327Z","steps":["trace[1788973386] 'agreement among raft nodes before linearized reading' (duration: 134.833164ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:02.878768Z","caller":"traceutil/trace.go:171","msg":"trace[1054698063] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"128.886796ms","start":"2026-02-08T22:15:02.749858Z","end":"2026-02-08T22:15:02.878745Z","steps":["trace[1054698063] 'process raft request' (duration: 127.970637ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:02.880841Z","caller":"traceutil/trace.go:171","msg":"trace[28575676] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"130.368094ms","start":"2026-02-08T22:15:02.750448Z","end":"2026-02-08T22:15:02.880816Z","steps":["trace[28575676] 'process raft request' (duration: 127.937027ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:04.621817Z","caller":"traceutil/trace.go:171","msg":"trace[1359126720] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"148.511574ms","start":"2026-02-08T22:15:04.473288Z","end":"2026-02-08T22:15:04.6218Z","steps":["trace[1359126720] 'process raft request' (duration: 148.409451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:06.247592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.562059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-08T22:15:06.247638Z","caller":"traceutil/trace.go:171","msg":"trace[1703319635] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:551; }","duration":"306.642752ms","start":"2026-02-08T22:15:05.940985Z","end":"2026-02-08T22:15:06.247627Z","steps":["trace[1703319635] 'range keys from in-memory index tree' (duration: 306.454656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:06.247655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:05.940968Z","time spent":"306.682453ms","remote":"127.0.0.1:35816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-08T22:15:06.247757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.612991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T22:15:06.247769Z","caller":"traceutil/trace.go:171","msg":"trace[2133570143] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:551; }","duration":"306.625661ms","start":"2026-02-08T22:15:05.94114Z","end":"2026-02-08T22:15:06.247765Z","steps":["trace[2133570143] 'range keys from in-memory index tree' (duration: 306.524878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:06.247781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:05.941087Z","time spent":"306.691223ms","remote":"127.0.0.1:35724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.200\" "} {"level":"warn","ts":"2026-02-08T22:15:13.195935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.025268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-08T22:15:13.19599Z","caller":"traceutil/trace.go:171","msg":"trace[1958741382] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:597; }","duration":"118.118911ms","start":"2026-02-08T22:15:13.07786Z","end":"2026-02-08T22:15:13.195979Z","steps":["trace[1958741382] 'range keys from in-memory index tree' (duration: 117.898835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:15.747286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.233783ms","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:15.747924Z","caller":"traceutil/trace.go:171","msg":"trace[1521871909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:612; }","duration":"106.925491ms","start":"2026-02-08T22:15:15.640981Z","end":"2026-02-08T22:15:15.747907Z","steps":["trace[1521871909] 'range keys from in-memory index tree' (duration: 106.055238ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:17.21204Z","caller":"traceutil/trace.go:171","msg":"trace[1650666022] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"206.840237ms","start":"2026-02-08T22:15:17.005182Z","end":"2026-02-08T22:15:17.212022Z","steps":["trace[1650666022] 'process raft request' (duration: 206.724914ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:17.212479Z","caller":"traceutil/trace.go:171","msg":"trace[55349956] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:657; }","duration":"155.725594ms","start":"2026-02-08T22:15:17.056732Z","end":"2026-02-08T22:15:17.212458Z","steps":["trace[55349956] 'read index received' (duration: 155.714784ms)","trace[55349956] 'applied index is now lower than readState.Index' (duration: 9.12µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:17.212636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.884888ms","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:17.212654Z","caller":"traceutil/trace.go:171","msg":"trace[1766378593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"155.9412ms","start":"2026-02-08T22:15:17.056706Z","end":"2026-02-08T22:15:17.212647Z","steps":["trace[1766378593] 'agreement among raft nodes before linearized reading' (duration: 155.822177ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:17.213111Z","caller":"traceutil/trace.go:171","msg":"trace[1082188446] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"132.32737ms","start":"2026-02-08T22:15:17.080773Z","end":"2026-02-08T22:15:17.213101Z","steps":["trace[1082188446] 'process raft request' (duration: 132.280399ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:20.203953Z","caller":"traceutil/trace.go:171","msg":"trace[1335890532] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"426.106095ms","start":"2026-02-08T22:15:19.777823Z","end":"2026-02-08T22:15:20.203929Z","steps":["trace[1335890532] 'process raft request' (duration: 425.955991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:20.204109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:19.777808Z","time spent":"426.225259ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:20.448352Z","caller":"traceutil/trace.go:171","msg":"trace[1593229565] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"236.482278ms","start":"2026-02-08T22:15:20.211853Z","end":"2026-02-08T22:15:20.448336Z","steps":["trace[1593229565] 'read index received' (duration: 178.389668ms)","trace[1593229565] 'applied index is now lower than readState.Index' (duration: 58.09191ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:20.448542Z","caller":"traceutil/trace.go:171","msg":"trace[2099728024] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"510.23588ms","start":"2026-02-08T22:15:19.938298Z","end":"2026-02-08T22:15:20.448534Z","steps":["trace[2099728024] 'process raft request' (duration: 451.961206ms)","trace[2099728024] 'compare' (duration: 57.892284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:20.448618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:19.938277Z","time spent":"510.292621ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:20.448777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.92021ms","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.448799Z","caller":"traceutil/trace.go:171","msg":"trace[1500105021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"236.960911ms","start":"2026-02-08T22:15:20.211832Z","end":"2026-02-08T22:15:20.448793Z","steps":["trace[1500105021] 'agreement among raft nodes before linearized reading' (duration: 236.9002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:20.44896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-gv46b\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-08T22:15:20.448978Z","caller":"traceutil/trace.go:171","msg":"trace[1372499757] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-gv46b; range_end:; response_count:1; response_revision:676; }","duration":"101.432316ms","start":"2026-02-08T22:15:20.34754Z","end":"2026-02-08T22:15:20.448972Z","steps":["trace[1372499757] 'agreement among raft nodes before linearized reading' (duration: 101.346944ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:20.708262Z","caller":"traceutil/trace.go:171","msg":"trace[221441824] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"252.649509ms","start":"2026-02-08T22:15:20.455573Z","end":"2026-02-08T22:15:20.708222Z","steps":["trace[221441824] 'process raft request' (duration: 241.551053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:21.405215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085533020471973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:15:21.905993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085533020471973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:15:22.248063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.021642ms","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:22.24817Z","caller":"traceutil/trace.go:171","msg":"trace[518284388] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:677; }","duration":"703.134075ms","start":"2026-02-08T22:15:21.545019Z","end":"2026-02-08T22:15:22.248154Z","steps":["trace[518284388] 'range keys from in-memory index tree' (duration: 703.012082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.250561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441699455s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:22.251267Z","caller":"traceutil/trace.go:171","msg":"trace[839218284] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.533232266s","start":"2026-02-08T22:15:20.718028Z","end":"2026-02-08T22:15:22.25126Z","steps":["trace[839218284] 'process raft request' (duration: 1.533209575s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.251362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.71802Z","time spent":"1.533323749s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:22.252098Z","caller":"traceutil/trace.go:171","msg":"trace[1804753530] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"1.544146198s","start":"2026-02-08T22:15:20.707945Z","end":"2026-02-08T22:15:22.252091Z","steps":["trace[1804753530] 'process raft request' (duration: 100.880451ms)","trace[1804753530] 'compare' (duration: 1.438922621s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:22.254944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.70793Z","time spent":"1.546993324s","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1614,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:22.25597Z","caller":"traceutil/trace.go:171","msg":"trace[194663781] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.544360903s","start":"2026-02-08T22:15:20.711602Z","end":"2026-02-08T22:15:22.255963Z","steps":["trace[194663781] 'process raft request' (duration: 1.539579016s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.256148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.711596Z","time spent":"1.544497287s","remote":"127.0.0.1:35874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:22.257616Z","caller":"traceutil/trace.go:171","msg":"trace[1986770] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.542690928s","start":"2026-02-08T22:15:20.714919Z","end":"2026-02-08T22:15:22.25761Z","steps":["trace[1986770] 'process raft request' (duration: 1.536298538s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.257714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.714909Z","time spent":"1.542787202s","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:23.540532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000225091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-08T22:15:23.540607Z","caller":"traceutil/trace.go:171","msg":"trace[308388379] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000338514s","start":"2026-02-08T22:15:21.540251Z","end":"2026-02-08T22:15:23.540589Z","steps":["trace[308388379] 'agreement among raft nodes before linearized reading' (duration: 2.000244412s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.540638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.540235Z","time spent":"2.000395846s","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:15:23.620189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.718903ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-08T22:15:23.620375Z","caller":"traceutil/trace.go:171","msg":"trace[1018763770] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.350823569s","start":"2026-02-08T22:15:22.269541Z","end":"2026-02-08T22:15:23.620364Z","steps":["trace[1018763770] 'process raft request' (duration: 1.350786168s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.620451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.269527Z","time spent":"1.350880421s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:23.620599Z","caller":"traceutil/trace.go:171","msg":"trace[1912347054] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"2.267990793s","start":"2026-02-08T22:15:21.352603Z","end":"2026-02-08T22:15:23.620594Z","steps":["trace[1912347054] 'process raft request' (duration: 2.267645304s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.620627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.352587Z","time spent":"2.268027075s","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:15:23.620713Z","caller":"traceutil/trace.go:171","msg":"trace[716475003] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:703; }","duration":"2.716107046s","start":"2026-02-08T22:15:20.9046Z","end":"2026-02-08T22:15:23.620707Z","steps":["trace[716475003] 'read index received' (duration: 1.084949489s)","trace[716475003] 'applied index is now lower than readState.Index' (duration: 1.631156937s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:23.620917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.716316972s","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:23.620937Z","caller":"traceutil/trace.go:171","msg":"trace[1316580569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"2.716398354s","start":"2026-02-08T22:15:20.904533Z","end":"2026-02-08T22:15:23.620932Z","steps":["trace[1316580569] 'agreement among raft nodes before linearized reading' (duration: 2.716327892s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.620952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:20.904515Z","time spent":"2.716433325s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:15:23.621044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395937236s","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:23.62106Z","caller":"traceutil/trace.go:171","msg":"trace[24740267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"2.395980898s","start":"2026-02-08T22:15:21.225076Z","end":"2026-02-08T22:15:23.621057Z","steps":["trace[24740267] 'agreement among raft nodes before linearized reading' (duration: 2.395940957s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.621072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.225056Z","time spent":"2.396013689s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:23.621208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413126062s","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:23.621227Z","caller":"traceutil/trace.go:171","msg":"trace[432075550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.413161563s","start":"2026-02-08T22:15:22.208061Z","end":"2026-02-08T22:15:23.621222Z","steps":["trace[432075550] 'agreement among raft nodes before linearized reading' (duration: 1.413120432s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.621243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.208051Z","time spent":"1.413188694s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:23.621301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414781656s","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:23.621317Z","caller":"traceutil/trace.go:171","msg":"trace[1895262848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.414827857s","start":"2026-02-08T22:15:22.206484Z","end":"2026-02-08T22:15:23.621312Z","steps":["trace[1895262848] 'agreement among raft nodes before linearized reading' (duration: 1.414806347s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.621329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:22.206464Z","time spent":"1.414861598s","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:15:23.621427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.272004271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-gcksv\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-08T22:15:23.621445Z","caller":"traceutil/trace.go:171","msg":"trace[632224572] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-gcksv; range_end:; response_count:1; response_revision:683; }","duration":"2.272064743s","start":"2026-02-08T22:15:21.349375Z","end":"2026-02-08T22:15:23.62144Z","steps":["trace[632224572] 'agreement among raft nodes before linearized reading' (duration: 2.272012492s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.62146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.349355Z","time spent":"2.272100843s","remote":"127.0.0.1:35886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4848,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-gcksv\" "} {"level":"warn","ts":"2026-02-08T22:15:23.621554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375539603s","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:23.62157Z","caller":"traceutil/trace.go:171","msg":"trace[1855771820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"2.375577323s","start":"2026-02-08T22:15:21.245988Z","end":"2026-02-08T22:15:23.621566Z","steps":["trace[1855771820] 'agreement among raft nodes before linearized reading' (duration: 2.375539662s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:23.621581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.245975Z","time spent":"2.375603335s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T22:15:27.349986Z","caller":"traceutil/trace.go:171","msg":"trace[383251886] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"143.119537ms","start":"2026-02-08T22:15:27.206849Z","end":"2026-02-08T22:15:27.349969Z","steps":["trace[383251886] 'read index received' (duration: 142.977893ms)","trace[383251886] 'applied index is now lower than readState.Index' (duration: 141.014µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:27.350115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.24522ms","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:27.350131Z","caller":"traceutil/trace.go:171","msg":"trace[1836203476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"143.334682ms","start":"2026-02-08T22:15:27.206791Z","end":"2026-02-08T22:15:27.350126Z","steps":["trace[1836203476] 'agreement among raft nodes before linearized reading' (duration: 143.2412ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:27.350324Z","caller":"traceutil/trace.go:171","msg":"trace[1088073956] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"528.203143ms","start":"2026-02-08T22:15:26.822116Z","end":"2026-02-08T22:15:27.350319Z","steps":["trace[1088073956] 'process raft request' (duration: 527.755551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:27.350372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:26.822085Z","time spent":"528.250525ms","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:15:31.846602Z","caller":"traceutil/trace.go:171","msg":"trace[707126179] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"152.778036ms","start":"2026-02-08T22:15:31.693728Z","end":"2026-02-08T22:15:31.846506Z","steps":["trace[707126179] 'read index received' (duration: 152.595151ms)","trace[707126179] 'applied index is now lower than readState.Index' (duration: 181.895µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:31.846733Z","caller":"traceutil/trace.go:171","msg":"trace[240454425] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"182.662614ms","start":"2026-02-08T22:15:31.664055Z","end":"2026-02-08T22:15:31.846718Z","steps":["trace[240454425] 'process raft request' (duration: 182.280564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:31.933387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.412965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T22:15:31.933436Z","caller":"traceutil/trace.go:171","msg":"trace[9237523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"235.490206ms","start":"2026-02-08T22:15:31.697934Z","end":"2026-02-08T22:15:31.933424Z","steps":["trace[9237523] 'agreement among raft nodes before linearized reading' (duration: 149.025445ms)","trace[9237523] 'range keys from in-memory index tree' (duration: 86.331628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:31.933461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.503527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T22:15:31.933494Z","caller":"traceutil/trace.go:171","msg":"trace[166338701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"235.556058ms","start":"2026-02-08T22:15:31.697927Z","end":"2026-02-08T22:15:31.933483Z","steps":["trace[166338701] 'agreement among raft nodes before linearized reading' (duration: 149.008244ms)","trace[166338701] 'range keys from in-memory index tree' (duration: 86.463262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:31.933587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.869574ms","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:31.933608Z","caller":"traceutil/trace.go:171","msg":"trace[1148101279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:715; }","duration":"239.912855ms","start":"2026-02-08T22:15:31.693685Z","end":"2026-02-08T22:15:31.933598Z","steps":["trace[1148101279] 'agreement among raft nodes before linearized reading' (duration: 153.266308ms)","trace[1148101279] 'range keys from in-memory index tree' (duration: 86.593805ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:44.091859Z","caller":"traceutil/trace.go:171","msg":"trace[1338060210] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"133.299884ms","start":"2026-02-08T22:15:43.958545Z","end":"2026-02-08T22:15:44.091845Z","steps":["trace[1338060210] 'process raft request' (duration: 132.921254ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:44.537526Z","caller":"traceutil/trace.go:171","msg":"trace[1960913924] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"104.141794ms","start":"2026-02-08T22:15:44.433362Z","end":"2026-02-08T22:15:44.537504Z","steps":["trace[1960913924] 'process raft request' (duration: 104.00269ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:45.980311Z","caller":"traceutil/trace.go:171","msg":"trace[1708735255] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"285.743851ms","start":"2026-02-08T22:15:45.694121Z","end":"2026-02-08T22:15:45.979865Z","steps":["trace[1708735255] 'process raft request' (duration: 285.227707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.70218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.471962ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-08T22:15:46.702238Z","caller":"traceutil/trace.go:171","msg":"trace[535950930] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"688.687224ms","start":"2026-02-08T22:15:46.01354Z","end":"2026-02-08T22:15:46.702228Z","steps":["trace[535950930] 'read index received' (duration: 53.11461ms)","trace[535950930] 'applied index is now lower than readState.Index' (duration: 635.571594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:46.702265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:45.982481Z","time spent":"719.775305ms","remote":"127.0.0.1:35724","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T22:15:46.702308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.767496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:15:46.702325Z","caller":"traceutil/trace.go:171","msg":"trace[2129396982] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:753; }","duration":"688.812948ms","start":"2026-02-08T22:15:46.013507Z","end":"2026-02-08T22:15:46.70232Z","steps":["trace[2129396982] 'agreement among raft nodes before linearized reading' (duration: 688.769457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.702343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.01346Z","time spent":"688.877649ms","remote":"127.0.0.1:35876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:46.702514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.430048ms","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:46.702528Z","caller":"traceutil/trace.go:171","msg":"trace[694938239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"605.49635ms","start":"2026-02-08T22:15:46.097028Z","end":"2026-02-08T22:15:46.702524Z","steps":["trace[694938239] 'agreement among raft nodes before linearized reading' (duration: 605.417718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.70254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.097008Z","time spent":"605.528821ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:46.702634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.068464ms","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:46.702673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.339937ms","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:46.70269Z","caller":"traceutil/trace.go:171","msg":"trace[1722592894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"574.378628ms","start":"2026-02-08T22:15:46.128307Z","end":"2026-02-08T22:15:46.702686Z","steps":["trace[1722592894] 'agreement among raft nodes before linearized reading' (duration: 574.342717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.702702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.128296Z","time spent":"574.403189ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:46.702818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.881385ms","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:15:46.702848Z","caller":"traceutil/trace.go:171","msg":"trace[1090703953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"156.934937ms","start":"2026-02-08T22:15:46.545907Z","end":"2026-02-08T22:15:46.702842Z","steps":["trace[1090703953] 'agreement among raft nodes before linearized reading' (duration: 156.828924ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:46.702668Z","caller":"traceutil/trace.go:171","msg":"trace[1836606653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"496.137026ms","start":"2026-02-08T22:15:46.206517Z","end":"2026-02-08T22:15:46.702654Z","steps":["trace[1836606653] 'agreement among raft nodes before linearized reading' (duration: 496.070354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.703366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.206504Z","time spent":"496.853995ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:15:46.702642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.206926ms","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:46.703655Z","caller":"traceutil/trace.go:171","msg":"trace[722092686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"574.230154ms","start":"2026-02-08T22:15:46.129412Z","end":"2026-02-08T22:15:46.703642Z","steps":["trace[722092686] 'agreement among raft nodes before linearized reading' (duration: 573.196367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.703678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.129402Z","time spent":"574.269225ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:46.70463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.590189ms","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:46.704661Z","caller":"traceutil/trace.go:171","msg":"trace[1372920770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"597.664241ms","start":"2026-02-08T22:15:46.106991Z","end":"2026-02-08T22:15:46.704656Z","steps":["trace[1372920770] 'agreement among raft nodes before linearized reading' (duration: 595.75803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:46.704676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.106945Z","time spent":"597.728013ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T22:15:47.249344Z","caller":"traceutil/trace.go:171","msg":"trace[1992877670] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"542.837615ms","start":"2026-02-08T22:15:46.706481Z","end":"2026-02-08T22:15:47.249319Z","steps":["trace[1992877670] 'process raft request' (duration: 541.457608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:47.249521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.706472Z","time spent":"542.933367ms","remote":"127.0.0.1:35724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:47.286251Z","caller":"traceutil/trace.go:171","msg":"trace[251109130] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"578.331064ms","start":"2026-02-08T22:15:46.707901Z","end":"2026-02-08T22:15:47.286232Z","steps":["trace[251109130] 'process raft request' (duration: 578.127248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:47.286395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.707887Z","time spent":"578.413596ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:47.286741Z","caller":"traceutil/trace.go:171","msg":"trace[1990845883] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"577.236185ms","start":"2026-02-08T22:15:46.709483Z","end":"2026-02-08T22:15:47.286719Z","steps":["trace[1990845883] 'process raft request' (duration: 576.67839ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:47.286833Z","caller":"traceutil/trace.go:171","msg":"trace[787369901] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"576.258798ms","start":"2026-02-08T22:15:46.710564Z","end":"2026-02-08T22:15:47.286823Z","steps":["trace[787369901] 'process raft request' (duration: 576.087714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:47.286856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.709476Z","time spent":"577.320117ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:47.286875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.710558Z","time spent":"576.294169ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:47.287055Z","caller":"traceutil/trace.go:171","msg":"trace[1185547941] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"576.256778ms","start":"2026-02-08T22:15:46.710792Z","end":"2026-02-08T22:15:47.287049Z","steps":["trace[1185547941] 'process raft request' (duration: 576.003732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:47.287091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.710786Z","time spent":"576.285399ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:47.287273Z","caller":"traceutil/trace.go:171","msg":"trace[1101584489] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"574.982714ms","start":"2026-02-08T22:15:46.712283Z","end":"2026-02-08T22:15:47.287265Z","steps":["trace[1101584489] 'process raft request' (duration: 574.742538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:47.287306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:46.712271Z","time spent":"575.016645ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:59.489944Z","caller":"traceutil/trace.go:171","msg":"trace[726428640] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"105.863371ms","start":"2026-02-08T22:15:59.384063Z","end":"2026-02-08T22:15:59.489927Z","steps":["trace[726428640] 'read index received' (duration: 81.368446ms)","trace[726428640] 'applied index is now lower than readState.Index' (duration: 24.494005ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:59.490146Z","caller":"traceutil/trace.go:171","msg":"trace[1472819454] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"112.110618ms","start":"2026-02-08T22:15:59.378029Z","end":"2026-02-08T22:15:59.49014Z","steps":["trace[1472819454] 'process raft request' (duration: 111.746908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.490308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.231291ms","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.490325Z","caller":"traceutil/trace.go:171","msg":"trace[1491935164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"106.271822ms","start":"2026-02-08T22:15:59.384048Z","end":"2026-02-08T22:15:59.490319Z","steps":["trace[1491935164] 'agreement among raft nodes before linearized reading' (duration: 106.19961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.93726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.291957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:59.937356Z","caller":"traceutil/trace.go:171","msg":"trace[252155689] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"442.243684ms","start":"2026-02-08T22:15:59.495101Z","end":"2026-02-08T22:15:59.937345Z","steps":["trace[252155689] 'process raft request' (duration: 442.212753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:59.937389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.495095Z","time spent":"442.278326ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:59.937548Z","caller":"traceutil/trace.go:171","msg":"trace[91326247] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"443.789337ms","start":"2026-02-08T22:15:59.493753Z","end":"2026-02-08T22:15:59.937543Z","steps":["trace[91326247] 'process raft request' (duration: 87.166991ms)","trace[91326247] 'compare' (duration: 356.119362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:59.937574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:59.493749Z","time spent":"443.812067ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:16:01.795347Z","caller":"traceutil/trace.go:171","msg":"trace[1067174483] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"236.800261ms","start":"2026-02-08T22:16:01.55853Z","end":"2026-02-08T22:16:01.79533Z","steps":["trace[1067174483] 'process raft request' (duration: 210.811157ms)","trace[1067174483] 'compare' (duration: 25.699947ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:16:01.831511Z","caller":"traceutil/trace.go:171","msg":"trace[1013140394] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"272.711941ms","start":"2026-02-08T22:16:01.558783Z","end":"2026-02-08T22:16:01.831495Z","steps":["trace[1013140394] 'process raft request' (duration: 272.603058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:02.109033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.787826ms","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:02.109087Z","caller":"traceutil/trace.go:171","msg":"trace[1087670593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:848; }","duration":"167.888899ms","start":"2026-02-08T22:16:01.941184Z","end":"2026-02-08T22:16:02.109073Z","steps":["trace[1087670593] 'range keys from in-memory index tree' (duration: 167.56101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:02.109185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.867968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T22:16:02.109228Z","caller":"traceutil/trace.go:171","msg":"trace[1037684763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"167.948111ms","start":"2026-02-08T22:16:01.94127Z","end":"2026-02-08T22:16:02.109218Z","steps":["trace[1037684763] 'range keys from in-memory index tree' (duration: 167.753556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:16.490115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.918212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:16:16.490207Z","caller":"traceutil/trace.go:171","msg":"trace[1318615923] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:887; }","duration":"434.046925ms","start":"2026-02-08T22:16:16.05614Z","end":"2026-02-08T22:16:16.490187Z","steps":["trace[1318615923] 'count revisions from in-memory index tree' (duration: 433.86367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:16.490257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:16.056127Z","time spent":"434.118858ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:16:16.490433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:16.152657Z","time spent":"337.773792ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T22:16:16.490717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.202227ms","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:16:16.490757Z","caller":"traceutil/trace.go:171","msg":"trace[1377534833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"306.264619ms","start":"2026-02-08T22:16:16.184479Z","end":"2026-02-08T22:16:16.490744Z","steps":["trace[1377534833] 'range keys from in-memory index tree' (duration: 306.070554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:16.49079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:16.184466Z","time spent":"306.31698ms","remote":"127.0.0.1:35966","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:16.491338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.260057ms","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:16.491376Z","caller":"traceutil/trace.go:171","msg":"trace[683338881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"285.311699ms","start":"2026-02-08T22:16:16.206055Z","end":"2026-02-08T22:16:16.491366Z","steps":["trace[683338881] 'range keys from in-memory index tree' (duration: 285.164835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:16.491685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.521276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:16:16.491722Z","caller":"traceutil/trace.go:171","msg":"trace[612739504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"125.582038ms","start":"2026-02-08T22:16:16.366131Z","end":"2026-02-08T22:16:16.491713Z","steps":["trace[612739504] 'range keys from in-memory index tree' (duration: 125.468765ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:18.687454Z","caller":"traceutil/trace.go:171","msg":"trace[238395191] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"175.097062ms","start":"2026-02-08T22:16:18.512334Z","end":"2026-02-08T22:16:18.687431Z","steps":["trace[238395191] 'process raft request' (duration: 168.371492ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:19.939996Z","caller":"traceutil/trace.go:171","msg":"trace[572160093] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"132.329338ms","start":"2026-02-08T22:16:19.807637Z","end":"2026-02-08T22:16:19.939966Z","steps":["trace[572160093] 'read index received' (duration: 112.484998ms)","trace[572160093] 'applied index is now lower than readState.Index' (duration: 19.84349ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:16:19.940086Z","caller":"traceutil/trace.go:171","msg":"trace[999920369] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"196.041831ms","start":"2026-02-08T22:16:19.744038Z","end":"2026-02-08T22:16:19.94008Z","steps":["trace[999920369] 'process raft request' (duration: 176.064468ms)","trace[999920369] 'compare' (duration: 19.629594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:16:19.940276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.624996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:16:19.940306Z","caller":"traceutil/trace.go:171","msg":"trace[304836918] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:899; }","duration":"132.695957ms","start":"2026-02-08T22:16:19.807604Z","end":"2026-02-08T22:16:19.9403Z","steps":["trace[304836918] 'agreement among raft nodes before linearized reading' (duration: 132.601345ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:20.217205Z","caller":"traceutil/trace.go:171","msg":"trace[718595018] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"264.981074ms","start":"2026-02-08T22:16:19.952198Z","end":"2026-02-08T22:16:20.217179Z","steps":["trace[718595018] 'process raft request' (duration: 177.249569ms)","trace[718595018] 'compare' (duration: 87.614992ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:16:20.232279Z","caller":"traceutil/trace.go:171","msg":"trace[738679940] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"279.998986ms","start":"2026-02-08T22:16:19.952266Z","end":"2026-02-08T22:16:20.232265Z","steps":["trace[738679940] 'process raft request' (duration: 279.689148ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:20.232439Z","caller":"traceutil/trace.go:171","msg":"trace[1878722924] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"278.738011ms","start":"2026-02-08T22:16:19.953696Z","end":"2026-02-08T22:16:20.232434Z","steps":["trace[1878722924] 'process raft request' (duration: 278.327241ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:24.296387Z","caller":"traceutil/trace.go:171","msg":"trace[1197353342] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"498.016696ms","start":"2026-02-08T22:16:23.798354Z","end":"2026-02-08T22:16:24.29637Z","steps":["trace[1197353342] 'read index received' (duration: 497.992576ms)","trace[1197353342] 'applied index is now lower than readState.Index' (duration: 23.42µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:16:24.296594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.224452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:16:24.296645Z","caller":"traceutil/trace.go:171","msg":"trace[1543092401] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:927; }","duration":"498.447357ms","start":"2026-02-08T22:16:23.798186Z","end":"2026-02-08T22:16:24.296633Z","steps":["trace[1543092401] 'agreement among raft nodes before linearized reading' (duration: 498.339904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:24.296673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:23.798172Z","time spent":"498.494139ms","remote":"127.0.0.1:35792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-08T22:16:24.296916Z","caller":"traceutil/trace.go:171","msg":"trace[1107046430] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"527.738651ms","start":"2026-02-08T22:16:23.769168Z","end":"2026-02-08T22:16:24.296907Z","steps":["trace[1107046430] 'process raft request' (duration: 527.102084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:24.296983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:23.76916Z","time spent":"527.776701ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:16:24.419205Z","caller":"traceutil/trace.go:171","msg":"trace[1093270448] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"118.832077ms","start":"2026-02-08T22:16:24.300357Z","end":"2026-02-08T22:16:24.41919Z","steps":["trace[1093270448] 'process raft request' (duration: 118.719134ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:24.421736Z","caller":"traceutil/trace.go:171","msg":"trace[783600127] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"118.53549ms","start":"2026-02-08T22:16:24.303188Z","end":"2026-02-08T22:16:24.421724Z","steps":["trace[783600127] 'process raft request' (duration: 118.468768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:30.859843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.74047ms","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:30.859918Z","caller":"traceutil/trace.go:171","msg":"trace[1380832221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"125.836014ms","start":"2026-02-08T22:16:30.734066Z","end":"2026-02-08T22:16:30.859902Z","steps":["trace[1380832221] 'range keys from in-memory index tree' (duration: 125.576607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:30.860071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.372636ms","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:16:30.860142Z","caller":"traceutil/trace.go:171","msg":"trace[2063065060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"409.483959ms","start":"2026-02-08T22:16:30.450636Z","end":"2026-02-08T22:16:30.86012Z","steps":["trace[2063065060] 'range keys from in-memory index tree' (duration: 409.186341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:30.860181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:30.450618Z","time spent":"409.555801ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:16:30.860429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.774683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-08T22:16:30.860438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.901223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T22:16:30.860459Z","caller":"traceutil/trace.go:171","msg":"trace[741947048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"404.802163ms","start":"2026-02-08T22:16:30.455646Z","end":"2026-02-08T22:16:30.860448Z","steps":["trace[741947048] 'range keys from in-memory index tree' (duration: 404.666281ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:30.860474Z","caller":"traceutil/trace.go:171","msg":"trace[1546729575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"230.975895ms","start":"2026-02-08T22:16:30.629488Z","end":"2026-02-08T22:16:30.860464Z","steps":["trace[1546729575] 'range keys from in-memory index tree' (duration: 230.799351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:30.860487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:16:30.455592Z","time spent":"404.889326ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T22:16:31.00168Z","caller":"traceutil/trace.go:171","msg":"trace[1734094803] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"133.283402ms","start":"2026-02-08T22:16:30.868367Z","end":"2026-02-08T22:16:31.00165Z","steps":["trace[1734094803] 'process raft request' (duration: 132.933263ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:31.040951Z","caller":"traceutil/trace.go:171","msg":"trace[1335232107] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"172.339567ms","start":"2026-02-08T22:16:30.868593Z","end":"2026-02-08T22:16:31.040932Z","steps":["trace[1335232107] 'process raft request' (duration: 172.258184ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:31.041494Z","caller":"traceutil/trace.go:171","msg":"trace[750525545] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"172.953892ms","start":"2026-02-08T22:16:30.868527Z","end":"2026-02-08T22:16:31.041481Z","steps":["trace[750525545] 'process raft request' (duration: 172.075479ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:31.041785Z","caller":"traceutil/trace.go:171","msg":"trace[2094619451] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"173.198589ms","start":"2026-02-08T22:16:30.868563Z","end":"2026-02-08T22:16:31.041762Z","steps":["trace[2094619451] 'process raft request' (duration: 172.167571ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:34.624673Z","caller":"traceutil/trace.go:171","msg":"trace[1420329102] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"220.464173ms","start":"2026-02-08T22:16:34.40419Z","end":"2026-02-08T22:16:34.624654Z","steps":["trace[1420329102] 'process raft request' (duration: 219.394044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:19:44.228716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.713831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-08T22:19:44.228796Z","caller":"traceutil/trace.go:171","msg":"trace[56726288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1802; }","duration":"198.852195ms","start":"2026-02-08T22:19:44.029928Z","end":"2026-02-08T22:19:44.228781Z","steps":["trace[56726288] 'range keys from in-memory index tree' (duration: 198.570987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:19:44.228798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.468695ms","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:19:44.228844Z","caller":"traceutil/trace.go:171","msg":"trace[395971838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1802; }","duration":"195.535726ms","start":"2026-02-08T22:19:44.033297Z","end":"2026-02-08T22:19:44.228833Z","steps":["trace[395971838] 'range keys from in-memory index tree' (duration: 195.373622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:19:44.228943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.042684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-08T22:19:44.228957Z","caller":"traceutil/trace.go:171","msg":"trace[1980366973] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1802; }","duration":"166.077735ms","start":"2026-02-08T22:19:44.062873Z","end":"2026-02-08T22:19:44.228951Z","steps":["trace[1980366973] 'range keys from in-memory index tree' (duration: 165.984112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:19:44.229047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.15164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:19:44.22906Z","caller":"traceutil/trace.go:171","msg":"trace[1708076135] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1802; }","duration":"119.189481ms","start":"2026-02-08T22:19:44.109866Z","end":"2026-02-08T22:19:44.229056Z","steps":["trace[1708076135] 'count revisions from in-memory index tree' (duration: 119.104939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:19:44.229089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.651435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-08T22:19:44.229111Z","caller":"traceutil/trace.go:171","msg":"trace[1124818031] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1802; }","duration":"323.712346ms","start":"2026-02-08T22:19:43.905392Z","end":"2026-02-08T22:19:44.229104Z","steps":["trace[1124818031] 'range keys from in-memory index tree' (duration: 323.505451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:19:44.22913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:19:43.905372Z","time spent":"323.752598ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-08T22:19:44.229254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.454514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T22:19:44.22927Z","caller":"traceutil/trace.go:171","msg":"trace[709295708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1802; }","duration":"264.553058ms","start":"2026-02-08T22:19:43.964712Z","end":"2026-02-08T22:19:44.229266Z","steps":["trace[709295708] 'range keys from in-memory index tree' (duration: 264.366023ms)"],"step_count":1}