{"level":"warn","ts":"2026-02-05T05:26:41.672593Z","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-05T05:26:41.672686Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-05T05:26:41.672773Z","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-05T05:26:41.672787Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-05T05:26:41.672804Z","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-05T05:26:41.673422Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-05T05:26:41.673514Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-05T05:26:41.683021Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.242127ms"} {"level":"info","ts":"2026-02-05T05:26:41.706126Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-05T05:26:41.706262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:26:41.70634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-05T05:26:41.706406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:26:41.706439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-05T05:26:41.706516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-05T05:26:41.719699Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:26:41.722508Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:26:41.724172Z","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-05T05:26:41.726493Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:26:41.726749Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:26:41.726959Z","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-05T05:26:41.727034Z","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-05T05:26:41.727074Z","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-05T05:26:41.738026Z","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-05T05:26:41.738247Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-05T05:26:41.738401Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-05T05:26:41.738646Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:26:41.738768Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:26:41.739499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-05T05:26:41.739622Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-05T05:26:41.806949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:26:41.807085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:26:41.807158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-05T05:26:41.807206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:26:41.807244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-05T05:26:41.807295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-05T05:26:41.807335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-05T05:26:41.808559Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:26:41.808605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:26:41.808762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:26:41.809079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:26:41.809095Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:26:41.810491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:26:41.820226Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-05T05:26:41.865328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:26:41.880111Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:26:41.880317Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:26:41.880401Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-05T05:26:53.300974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.02888ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-05T05:26:59.142713Z","caller":"traceutil/trace.go:171","msg":"trace[2047562942] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"575.924006ms","start":"2026-02-05T05:26:58.566769Z","end":"2026-02-05T05:26:59.142693Z","steps":["trace[2047562942] 'process raft request' (duration: 575.811013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:26:59.14327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:58.566753Z","time spent":"576.038548ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:00.722332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066378937s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:27:00.723215Z","caller":"traceutil/trace.go:171","msg":"trace[440390761] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"1.509096925s","start":"2026-02-05T05:26:59.2141Z","end":"2026-02-05T05:27:00.723197Z","steps":["trace[440390761] 'read index received' (duration: 441.732401ms)","trace[440390761] 'applied index is now lower than readState.Index' (duration: 1.067362814s)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:00.723496Z","caller":"traceutil/trace.go:171","msg":"trace[1349666122] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"2.155003428s","start":"2026-02-05T05:26:58.568478Z","end":"2026-02-05T05:27:00.723482Z","steps":["trace[1349666122] 'process raft request' (duration: 1.087374379s)","trace[1349666122] 'compare' (duration: 1.066187854s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:00.723589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:58.568468Z","time spent":"2.155058488s","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:00.72376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509666485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:00.723799Z","caller":"traceutil/trace.go:171","msg":"trace[132043209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"1.509718746s","start":"2026-02-05T05:26:59.214055Z","end":"2026-02-05T05:27:00.723774Z","steps":["trace[132043209] 'agreement among raft nodes before linearized reading' (duration: 1.509681696s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:00.723817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:59.214009Z","time spent":"1.509804258s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:00.723937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279395068s","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-05T05:27:00.723973Z","caller":"traceutil/trace.go:171","msg":"trace[1413134774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:382; }","duration":"1.27946787s","start":"2026-02-05T05:26:59.444499Z","end":"2026-02-05T05:27:00.723967Z","steps":["trace[1413134774] 'agreement among raft nodes before linearized reading' (duration: 1.279410899s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:00.723991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:59.44448Z","time spent":"1.27950694s","remote":"127.0.0.1:46354","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-05T05:27:00.724148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.751184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:27:00.724166Z","caller":"traceutil/trace.go:171","msg":"trace[252116485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:382; }","duration":"318.826105ms","start":"2026-02-05T05:27:00.405335Z","end":"2026-02-05T05:27:00.724161Z","steps":["trace[252116485] 'agreement among raft nodes before linearized reading' (duration: 318.792604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:00.724182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:00.405314Z","time spent":"318.864645ms","remote":"127.0.0.1:46354","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-05T05:27:00.724301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074445475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-05T05:27:00.724318Z","caller":"traceutil/trace.go:171","msg":"trace[1590214027] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:382; }","duration":"1.074493446s","start":"2026-02-05T05:26:59.64982Z","end":"2026-02-05T05:27:00.724313Z","steps":["trace[1590214027] 'agreement among raft nodes before linearized reading' (duration: 1.074443526s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:00.724335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:26:59.649802Z","time spent":"1.074528057s","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-05T05:27:15.359458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410863124,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:27:15.540215Z","caller":"traceutil/trace.go:171","msg":"trace[1664311179] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"740.563082ms","start":"2026-02-05T05:27:14.79963Z","end":"2026-02-05T05:27:15.540193Z","steps":["trace[1664311179] 'process raft request' (duration: 740.43918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:15.540358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:14.799614Z","time spent":"740.663533ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:15.545231Z","caller":"traceutil/trace.go:171","msg":"trace[1396897119] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"686.799476ms","start":"2026-02-05T05:27:14.858423Z","end":"2026-02-05T05:27:15.545222Z","steps":["trace[1396897119] 'read index received' (duration: 682.340398ms)","trace[1396897119] 'applied index is now lower than readState.Index' (duration: 4.458678ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:15.545356Z","caller":"traceutil/trace.go:171","msg":"trace[1319549991] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"465.015863ms","start":"2026-02-05T05:27:15.080333Z","end":"2026-02-05T05:27:15.545349Z","steps":["trace[1319549991] 'process raft request' (duration: 464.84664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:15.545401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:15.080312Z","time spent":"465.059565ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:15.545535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.111821ms","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-05T05:27:15.545556Z","caller":"traceutil/trace.go:171","msg":"trace[2112049192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"687.176462ms","start":"2026-02-05T05:27:14.858372Z","end":"2026-02-05T05:27:15.545549Z","steps":["trace[2112049192] 'agreement among raft nodes before linearized reading' (duration: 687.100161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:15.545573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:14.858352Z","time spent":"687.216943ms","remote":"127.0.0.1:46354","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-05T05:27:15.545654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.191638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:27:15.54567Z","caller":"traceutil/trace.go:171","msg":"trace[162509551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"667.236679ms","start":"2026-02-05T05:27:14.878428Z","end":"2026-02-05T05:27:15.545665Z","steps":["trace[162509551] 'agreement among raft nodes before linearized reading' (duration: 667.206709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:15.545685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:14.878412Z","time spent":"667.26959ms","remote":"127.0.0.1:46354","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-05T05:27:15.545815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.308247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T05:27:15.545835Z","caller":"traceutil/trace.go:171","msg":"trace[1293993152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"332.360757ms","start":"2026-02-05T05:27:15.213469Z","end":"2026-02-05T05:27:15.54583Z","steps":["trace[1293993152] 'agreement among raft nodes before linearized reading' (duration: 332.304286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:15.545865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:15.213453Z","time spent":"332.407218ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:15.545936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.461819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:15.545951Z","caller":"traceutil/trace.go:171","msg":"trace[1778744599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"332.49867ms","start":"2026-02-05T05:27:15.213448Z","end":"2026-02-05T05:27:15.545947Z","steps":["trace[1778744599] 'agreement among raft nodes before linearized reading' (duration: 332.47491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:15.545966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:15.213439Z","time spent":"332.52442ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:23.711423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410863182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:27:24.212351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410863182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:27:24.29795Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.260618364s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:27:24.298272Z","caller":"traceutil/trace.go:171","msg":"trace[1050619511] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"1.086960471s","start":"2026-02-05T05:27:23.211291Z","end":"2026-02-05T05:27:24.298252Z","steps":["trace[1050619511] 'read index received' (duration: 1.086769798s)","trace[1050619511] 'applied index is now lower than readState.Index' (duration: 189.993µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:24.29835Z","caller":"traceutil/trace.go:171","msg":"trace[153382301] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.261098271s","start":"2026-02-05T05:27:23.037243Z","end":"2026-02-05T05:27:24.298341Z","steps":["trace[153382301] 'process raft request' (duration: 1.260875628s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:24.298447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.037231Z","time spent":"1.261142893s","remote":"127.0.0.1:46274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:24.298825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.74701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:27:24.298948Z","caller":"traceutil/trace.go:171","msg":"trace[716188930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"706.934353ms","start":"2026-02-05T05:27:23.591996Z","end":"2026-02-05T05:27:24.29893Z","steps":["trace[716188930] 'agreement among raft nodes before linearized reading' (duration: 706.677938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:24.298989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.59197Z","time spent":"707.009974ms","remote":"127.0.0.1:46354","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-05T05:27:24.299173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087878897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:24.2992Z","caller":"traceutil/trace.go:171","msg":"trace[1373987460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"1.087930538s","start":"2026-02-05T05:27:23.21126Z","end":"2026-02-05T05:27:24.299191Z","steps":["trace[1373987460] 'agreement among raft nodes before linearized reading' (duration: 1.087887218s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:24.299224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.211248Z","time spent":"1.087970699s","remote":"127.0.0.1:46126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:24.299421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.380774ms","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-05T05:27:24.299449Z","caller":"traceutil/trace.go:171","msg":"trace[2018988562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"695.422624ms","start":"2026-02-05T05:27:23.604017Z","end":"2026-02-05T05:27:24.29944Z","steps":["trace[2018988562] 'agreement among raft nodes before linearized reading' (duration: 695.333093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:24.299477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.604011Z","time spent":"695.460915ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:24.29963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.593307ms","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-05T05:27:24.299655Z","caller":"traceutil/trace.go:171","msg":"trace[643868917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"702.635689ms","start":"2026-02-05T05:27:23.597011Z","end":"2026-02-05T05:27:24.299647Z","steps":["trace[643868917] 'agreement among raft nodes before linearized reading' (duration: 702.564528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:24.299682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.597002Z","time spent":"702.674349ms","remote":"127.0.0.1:46354","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-05T05:27:24.299829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.660111ms","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-05T05:27:24.299854Z","caller":"traceutil/trace.go:171","msg":"trace[1297686972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"705.706381ms","start":"2026-02-05T05:27:23.59414Z","end":"2026-02-05T05:27:24.299847Z","steps":["trace[1297686972] 'agreement among raft nodes before linearized reading' (duration: 705.63397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:24.299878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:23.594129Z","time spent":"705.743112ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T05:27:26.245965Z","caller":"traceutil/trace.go:171","msg":"trace[1402764003] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:528; }","duration":"201.735357ms","start":"2026-02-05T05:27:26.044211Z","end":"2026-02-05T05:27:26.245947Z","steps":["trace[1402764003] 'read index received' (duration: 117.295852ms)","trace[1402764003] 'applied index is now lower than readState.Index' (duration: 84.438765ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:26.246061Z","caller":"traceutil/trace.go:171","msg":"trace[1341581940] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"306.024783ms","start":"2026-02-05T05:27:25.940025Z","end":"2026-02-05T05:27:26.24605Z","steps":["trace[1341581940] 'process raft request' (duration: 221.471057ms)","trace[1341581940] 'compare' (duration: 83.599161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:26.246123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:25.94002Z","time spent":"306.062144ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:27:26.246332Z","caller":"traceutil/trace.go:171","msg":"trace[979493691] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"305.187418ms","start":"2026-02-05T05:27:25.941136Z","end":"2026-02-05T05:27:26.246324Z","steps":["trace[979493691] 'process raft request' (duration: 304.09012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:26.246372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:25.941131Z","time spent":"305.22123ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:27:26.246499Z","caller":"traceutil/trace.go:171","msg":"trace[2073678465] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"295.694177ms","start":"2026-02-05T05:27:25.950797Z","end":"2026-02-05T05:27:26.246492Z","steps":["trace[2073678465] 'process raft request' (duration: 294.959664ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:26.248757Z","caller":"traceutil/trace.go:171","msg":"trace[1816071587] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"295.256288ms","start":"2026-02-05T05:27:25.95349Z","end":"2026-02-05T05:27:26.248746Z","steps":["trace[1816071587] 'process raft request' (duration: 292.369938ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:26.251522Z","caller":"traceutil/trace.go:171","msg":"trace[526633063] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"205.108594ms","start":"2026-02-05T05:27:26.046404Z","end":"2026-02-05T05:27:26.251512Z","steps":["trace[526633063] 'process raft request' (duration: 199.497828ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:26.251639Z","caller":"traceutil/trace.go:171","msg":"trace[466328417] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"136.761566ms","start":"2026-02-05T05:27:26.114857Z","end":"2026-02-05T05:27:26.251619Z","steps":["trace[466328417] 'process raft request' (duration: 131.069048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:26.25217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.960664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-stcbp\" ","response":"range_response_count:1 size:16912"} {"level":"info","ts":"2026-02-05T05:27:26.25221Z","caller":"traceutil/trace.go:171","msg":"trace[1272941514] range","detail":"{range_begin:/registry/pods/kube-system/cilium-stcbp; range_end:; response_count:1; response_revision:521; }","duration":"208.041436ms","start":"2026-02-05T05:27:26.044159Z","end":"2026-02-05T05:27:26.2522Z","steps":["trace[1272941514] 'agreement among raft nodes before linearized reading' (duration: 207.580237ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:26.255366Z","caller":"traceutil/trace.go:171","msg":"trace[1513586124] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"304.451366ms","start":"2026-02-05T05:27:25.950905Z","end":"2026-02-05T05:27:26.255356Z","steps":["trace[1513586124] 'process raft request' (duration: 294.915042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:26.255437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:25.9509Z","time spent":"304.489507ms","remote":"127.0.0.1:46158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:46.149541Z","caller":"traceutil/trace.go:171","msg":"trace[815972252] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"224.581091ms","start":"2026-02-05T05:27:45.924944Z","end":"2026-02-05T05:27:46.149525Z","steps":["trace[815972252] 'process raft request' (duration: 224.475159ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:46.149879Z","caller":"traceutil/trace.go:171","msg":"trace[814392494] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:712; }","duration":"191.854497ms","start":"2026-02-05T05:27:45.958016Z","end":"2026-02-05T05:27:46.149871Z","steps":["trace[814392494] 'read index received' (duration: 191.851577ms)","trace[814392494] 'applied index is now lower than readState.Index' (duration: 2.55µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:46.14999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.965498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-05T05:27:46.150005Z","caller":"traceutil/trace.go:171","msg":"trace[978367976] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:693; }","duration":"191.991609ms","start":"2026-02-05T05:27:45.958009Z","end":"2026-02-05T05:27:46.15Z","steps":["trace[978367976] 'agreement among raft nodes before linearized reading' (duration: 191.897097ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:46.166091Z","caller":"traceutil/trace.go:171","msg":"trace[474829531] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"147.36483ms","start":"2026-02-05T05:27:46.018705Z","end":"2026-02-05T05:27:46.16607Z","steps":["trace[474829531] 'process raft request' (duration: 146.78579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.979742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.997618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-05T05:27:46.980007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.932579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:46.980037Z","caller":"traceutil/trace.go:171","msg":"trace[1115300634] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:694; }","duration":"577.988031ms","start":"2026-02-05T05:27:46.402037Z","end":"2026-02-05T05:27:46.980025Z","steps":["trace[1115300634] 'count revisions from in-memory index tree' (duration: 577.878059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.980063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:46.402023Z","time spent":"578.033421ms","remote":"127.0.0.1:46302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:27:46.980236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.905677ms","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-05T05:27:46.980274Z","caller":"traceutil/trace.go:171","msg":"trace[92966976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"549.977448ms","start":"2026-02-05T05:27:46.430289Z","end":"2026-02-05T05:27:46.980267Z","steps":["trace[92966976] 'range keys from in-memory index tree' (duration: 549.817845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.980291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:46.430279Z","time spent":"550.008008ms","remote":"127.0.0.1:46354","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-05T05:27:46.980422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.293021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:27:46.980452Z","caller":"traceutil/trace.go:171","msg":"trace[2025424863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"547.322852ms","start":"2026-02-05T05:27:46.433124Z","end":"2026-02-05T05:27:46.980447Z","steps":["trace[2025424863] 'range keys from in-memory index tree' (duration: 547.19508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.980468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:46.433062Z","time spent":"547.401563ms","remote":"127.0.0.1:46354","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-05T05:27:46.980574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.885489ms","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-05T05:27:46.980593Z","caller":"traceutil/trace.go:171","msg":"trace[1898128921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"541.943369ms","start":"2026-02-05T05:27:46.438644Z","end":"2026-02-05T05:27:46.980587Z","steps":["trace[1898128921] 'range keys from in-memory index tree' (duration: 541.786476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.980607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:46.438623Z","time spent":"541.98123ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:46.980704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.105849ms","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-05T05:27:46.98072Z","caller":"traceutil/trace.go:171","msg":"trace[1747005934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"529.122869ms","start":"2026-02-05T05:27:46.451592Z","end":"2026-02-05T05:27:46.980715Z","steps":["trace[1747005934] 'range keys from in-memory index tree' (duration: 529.039906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.980735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:46.451567Z","time spent":"529.163869ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:27:46.981537Z","caller":"traceutil/trace.go:171","msg":"trace[298514667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"626.08628ms","start":"2026-02-05T05:27:46.353696Z","end":"2026-02-05T05:27:46.979782Z","steps":["trace[298514667] 'range keys from in-memory index tree' (duration: 625.854755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:46.981567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:46.353676Z","time spent":"627.88351ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T05:27:47.10213Z","caller":"traceutil/trace.go:171","msg":"trace[426538427] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"108.137504ms","start":"2026-02-05T05:27:46.99397Z","end":"2026-02-05T05:27:47.102107Z","steps":["trace[426538427] 'process raft request' (duration: 108.002741ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:47.386457Z","caller":"traceutil/trace.go:171","msg":"trace[902428777] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:714; }","duration":"172.251714ms","start":"2026-02-05T05:27:47.214183Z","end":"2026-02-05T05:27:47.386435Z","steps":["trace[902428777] 'read index received' (duration: 171.372098ms)","trace[902428777] 'applied index is now lower than readState.Index' (duration: 878.686µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:47.386706Z","caller":"traceutil/trace.go:171","msg":"trace[1249315950] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"386.549693ms","start":"2026-02-05T05:27:47.000144Z","end":"2026-02-05T05:27:47.386694Z","steps":["trace[1249315950] 'process raft request' (duration: 385.586296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:47.387086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:47.000138Z","time spent":"386.87458ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:47.387232Z","caller":"traceutil/trace.go:171","msg":"trace[1348251889] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"387.052553ms","start":"2026-02-05T05:27:47.000173Z","end":"2026-02-05T05:27:47.387225Z","steps":["trace[1348251889] 'process raft request' (duration: 386.103415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:47.387266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:47.000169Z","time spent":"387.079303ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:47.387474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.721469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:27:47.387545Z","caller":"traceutil/trace.go:171","msg":"trace[717019342] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:699; }","duration":"146.828211ms","start":"2026-02-05T05:27:47.240699Z","end":"2026-02-05T05:27:47.387527Z","steps":["trace[717019342] 'agreement among raft nodes before linearized reading' (duration: 146.651428ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:47.387568Z","caller":"traceutil/trace.go:171","msg":"trace[1374738500] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"387.293958ms","start":"2026-02-05T05:27:47.000267Z","end":"2026-02-05T05:27:47.387561Z","steps":["trace[1374738500] 'process raft request' (duration: 386.135916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:47.387621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:47.000264Z","time spent":"387.334858ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:47.3877Z","caller":"traceutil/trace.go:171","msg":"trace[735095498] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"387.44629ms","start":"2026-02-05T05:27:47.000246Z","end":"2026-02-05T05:27:47.387692Z","steps":["trace[735095498] 'process raft request' (duration: 386.075295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:47.387729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:47.000242Z","time spent":"387.4709ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:27:47.387768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.582258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:47.387867Z","caller":"traceutil/trace.go:171","msg":"trace[1950256423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"173.67335ms","start":"2026-02-05T05:27:47.214143Z","end":"2026-02-05T05:27:47.387816Z","steps":["trace[1950256423] 'agreement among raft nodes before linearized reading' (duration: 173.600219ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:03.683529Z","caller":"traceutil/trace.go:171","msg":"trace[1145655588] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"205.082484ms","start":"2026-02-05T05:28:03.47842Z","end":"2026-02-05T05:28:03.683503Z","steps":["trace[1145655588] 'read index received' (duration: 204.969612ms)","trace[1145655588] 'applied index is now lower than readState.Index' (duration: 112.142µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:03.683624Z","caller":"traceutil/trace.go:171","msg":"trace[204183308] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"219.660725ms","start":"2026-02-05T05:28:03.463957Z","end":"2026-02-05T05:28:03.683618Z","steps":["trace[204183308] 'process raft request' (duration: 219.443092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:03.683805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.548578ms","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-05T05:28:03.683857Z","caller":"traceutil/trace.go:171","msg":"trace[1955398111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"201.64157ms","start":"2026-02-05T05:28:03.482202Z","end":"2026-02-05T05:28:03.683844Z","steps":["trace[1955398111] 'agreement among raft nodes before linearized reading' (duration: 201.504928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:03.683946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.508752ms","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-05T05:28:03.683971Z","caller":"traceutil/trace.go:171","msg":"trace[1110570833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"205.557833ms","start":"2026-02-05T05:28:03.478406Z","end":"2026-02-05T05:28:03.683964Z","steps":["trace[1110570833] 'agreement among raft nodes before linearized reading' (duration: 205.460881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:03.68402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.377057ms","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-05T05:28:03.684043Z","caller":"traceutil/trace.go:171","msg":"trace[344927522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"200.411177ms","start":"2026-02-05T05:28:03.483625Z","end":"2026-02-05T05:28:03.684037Z","steps":["trace[344927522] 'agreement among raft nodes before linearized reading' (duration: 200.356686ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:06.136208Z","caller":"traceutil/trace.go:171","msg":"trace[471016347] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"442.851297ms","start":"2026-02-05T05:28:05.693329Z","end":"2026-02-05T05:28:06.136181Z","steps":["trace[471016347] 'read index received' (duration: 442.710303ms)","trace[471016347] 'applied index is now lower than readState.Index' (duration: 140.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:06.136421Z","caller":"traceutil/trace.go:171","msg":"trace[2073174893] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"445.960345ms","start":"2026-02-05T05:28:05.690453Z","end":"2026-02-05T05:28:06.136414Z","steps":["trace[2073174893] 'process raft request' (duration: 445.638189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:06.136494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:05.690438Z","time spent":"446.001145ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:06.136704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.343045ms","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-05T05:28:06.136726Z","caller":"traceutil/trace.go:171","msg":"trace[2070306059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"443.404836ms","start":"2026-02-05T05:28:05.693314Z","end":"2026-02-05T05:28:06.136719Z","steps":["trace[2070306059] 'agreement among raft nodes before linearized reading' (duration: 443.289314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:06.136744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:05.693307Z","time spent":"443.432807ms","remote":"127.0.0.1:46354","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-05T05:28:06.13685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.115097ms","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-05T05:28:06.136866Z","caller":"traceutil/trace.go:171","msg":"trace[1084620200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"438.305872ms","start":"2026-02-05T05:28:05.698556Z","end":"2026-02-05T05:28:06.136861Z","steps":["trace[1084620200] 'agreement among raft nodes before linearized reading' (duration: 438.260401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:06.136883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:05.698549Z","time spent":"438.329942ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:06.137009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.9834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T05:28:06.137034Z","caller":"traceutil/trace.go:171","msg":"trace[1813445371] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:752; }","duration":"370.029982ms","start":"2026-02-05T05:28:05.766999Z","end":"2026-02-05T05:28:06.137029Z","steps":["trace[1813445371] 'agreement among raft nodes before linearized reading' (duration: 369.975941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:06.137049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:05.766982Z","time spent":"370.063282ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.99\" "} {"level":"warn","ts":"2026-02-05T05:28:06.137182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.788921ms","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-05T05:28:06.137199Z","caller":"traceutil/trace.go:171","msg":"trace[1687173634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"437.815662ms","start":"2026-02-05T05:28:05.699378Z","end":"2026-02-05T05:28:06.137194Z","steps":["trace[1687173634] 'agreement among raft nodes before linearized reading' (duration: 437.768841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:06.137215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:05.699372Z","time spent":"437.838202ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:07.046722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.505222ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-05T05:28:07.046805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.138601Z","time spent":"908.200081ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T05:28:07.048155Z","caller":"traceutil/trace.go:171","msg":"trace[1968639575] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:774; }","duration":"834.848326ms","start":"2026-02-05T05:28:06.213291Z","end":"2026-02-05T05:28:07.048139Z","steps":["trace[1968639575] 'read index received' (duration: 342.924338ms)","trace[1968639575] 'applied index is now lower than readState.Index' (duration: 491.923088ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:07.048378Z","caller":"traceutil/trace.go:171","msg":"trace[1252560372] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"906.990919ms","start":"2026-02-05T05:28:06.141377Z","end":"2026-02-05T05:28:07.048368Z","steps":["trace[1252560372] 'process raft request' (duration: 906.586182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:07.048454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.141364Z","time spent":"907.03828ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:07.048586Z","caller":"traceutil/trace.go:171","msg":"trace[1416788242] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"904.37152ms","start":"2026-02-05T05:28:06.144206Z","end":"2026-02-05T05:28:07.048578Z","steps":["trace[1416788242] 'process raft request' (duration: 903.874241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:07.048632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.144198Z","time spent":"904.408621ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:07.048766Z","caller":"traceutil/trace.go:171","msg":"trace[820889693] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"904.291119ms","start":"2026-02-05T05:28:06.14445Z","end":"2026-02-05T05:28:07.048741Z","steps":["trace[820889693] 'process raft request' (duration: 903.661138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:07.048814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.144442Z","time spent":"904.34612ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:07.048946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.65718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:07.048973Z","caller":"traceutil/trace.go:171","msg":"trace[1318585291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"835.709782ms","start":"2026-02-05T05:28:06.213254Z","end":"2026-02-05T05:28:07.048964Z","steps":["trace[1318585291] 'agreement among raft nodes before linearized reading' (duration: 835.667021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:07.048998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.21324Z","time spent":"835.753223ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:28:07.054555Z","caller":"traceutil/trace.go:171","msg":"trace[899688771] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"329.681911ms","start":"2026-02-05T05:28:06.724867Z","end":"2026-02-05T05:28:07.054549Z","steps":["trace[899688771] 'process raft request' (duration: 329.559789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:07.054588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.724842Z","time spent":"329.727763ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:07.054693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.521216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:28:07.054707Z","caller":"traceutil/trace.go:171","msg":"trace[1819111804] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:757; }","duration":"709.562357ms","start":"2026-02-05T05:28:06.34514Z","end":"2026-02-05T05:28:07.054703Z","steps":["trace[1819111804] 'agreement among raft nodes before linearized reading' (duration: 709.536016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:07.054721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:06.345107Z","time spent":"709.609868ms","remote":"127.0.0.1:46532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:28:11.565276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410863767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:28:11.932683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.116497ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T05:28:11.932848Z","caller":"traceutil/trace.go:171","msg":"trace[1367594110] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"867.918113ms","start":"2026-02-05T05:28:11.064904Z","end":"2026-02-05T05:28:11.932822Z","steps":["trace[1367594110] 'read index received' (duration: 542.553891ms)","trace[1367594110] 'applied index is now lower than readState.Index' (duration: 325.362172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:11.933069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.145557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T05:28:11.933144Z","caller":"traceutil/trace.go:171","msg":"trace[395842546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"868.258869ms","start":"2026-02-05T05:28:11.064865Z","end":"2026-02-05T05:28:11.933124Z","steps":["trace[395842546] 'agreement among raft nodes before linearized reading' (duration: 868.069796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:11.933186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.064843Z","time spent":"868.33742ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:11.933426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.47903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:11.933446Z","caller":"traceutil/trace.go:171","msg":"trace[778731701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"720.514211ms","start":"2026-02-05T05:28:11.212925Z","end":"2026-02-05T05:28:11.933439Z","steps":["trace[778731701] 'agreement among raft nodes before linearized reading' (duration: 720.47957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:11.933464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.212916Z","time spent":"720.544781ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:11.933674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.316704ms","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-05T05:28:11.933694Z","caller":"traceutil/trace.go:171","msg":"trace[891574050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"751.370595ms","start":"2026-02-05T05:28:11.182317Z","end":"2026-02-05T05:28:11.933688Z","steps":["trace[891574050] 'agreement among raft nodes before linearized reading' (duration: 751.309594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:11.933711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.182299Z","time spent":"751.408255ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:28:11.933913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.180218ms","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-05T05:28:11.933969Z","caller":"traceutil/trace.go:171","msg":"trace[2106620401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"853.252879ms","start":"2026-02-05T05:28:11.080706Z","end":"2026-02-05T05:28:11.933959Z","steps":["trace[2106620401] 'agreement among raft nodes before linearized reading' (duration: 853.163477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:11.933991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.080696Z","time spent":"853.28977ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:11.935641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.645282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T05:28:11.935744Z","caller":"traceutil/trace.go:171","msg":"trace[27640936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"857.796144ms","start":"2026-02-05T05:28:11.077912Z","end":"2026-02-05T05:28:11.935708Z","steps":["trace[27640936] 'agreement among raft nodes before linearized reading' (duration: 855.063223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:11.935798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.077902Z","time spent":"857.885377ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T05:28:12.444391Z","caller":"traceutil/trace.go:171","msg":"trace[1154544777] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"501.546198ms","start":"2026-02-05T05:28:11.942821Z","end":"2026-02-05T05:28:12.444368Z","steps":["trace[1154544777] 'process raft request' (duration: 501.395506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.44489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.94281Z","time spent":"501.972076ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:12.822737Z","caller":"traceutil/trace.go:171","msg":"trace[312132718] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"878.299075ms","start":"2026-02-05T05:28:11.944418Z","end":"2026-02-05T05:28:12.822717Z","steps":["trace[312132718] 'process raft request' (duration: 878.245294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.822877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.944411Z","time spent":"878.395716ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:12.823034Z","caller":"traceutil/trace.go:171","msg":"trace[1242243313] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:789; }","duration":"648.199635ms","start":"2026-02-05T05:28:12.174811Z","end":"2026-02-05T05:28:12.82301Z","steps":["trace[1242243313] 'read index received' (duration: 269.521163ms)","trace[1242243313] 'applied index is now lower than readState.Index' (duration: 378.677342ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:12.823107Z","caller":"traceutil/trace.go:171","msg":"trace[1013478003] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"880.159029ms","start":"2026-02-05T05:28:11.942934Z","end":"2026-02-05T05:28:12.823093Z","steps":["trace[1013478003] 'process raft request' (duration: 879.60493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.823151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.942925Z","time spent":"880.203831ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:12.823236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.002912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:12.823272Z","caller":"traceutil/trace.go:171","msg":"trace[1185489994] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:769; }","duration":"648.050623ms","start":"2026-02-05T05:28:12.175215Z","end":"2026-02-05T05:28:12.823265Z","steps":["trace[1185489994] 'agreement among raft nodes before linearized reading' (duration: 647.997342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.823293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:12.175209Z","time spent":"648.078313ms","remote":"127.0.0.1:46450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:28:12.823408Z","caller":"traceutil/trace.go:171","msg":"trace[1978765688] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"878.950798ms","start":"2026-02-05T05:28:11.944449Z","end":"2026-02-05T05:28:12.8234Z","steps":["trace[1978765688] 'process raft request' (duration: 878.240634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.823447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:11.944445Z","time spent":"878.980288ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:12.823573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.763847ms","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-05T05:28:12.823591Z","caller":"traceutil/trace.go:171","msg":"trace[1598545920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"648.816437ms","start":"2026-02-05T05:28:12.174768Z","end":"2026-02-05T05:28:12.823585Z","steps":["trace[1598545920] 'agreement among raft nodes before linearized reading' (duration: 648.735686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.823607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:12.174752Z","time spent":"648.852029ms","remote":"127.0.0.1:46354","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-05T05:28:12.823683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.948449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:12.823714Z","caller":"traceutil/trace.go:171","msg":"trace[1637505304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"607.04014ms","start":"2026-02-05T05:28:12.216666Z","end":"2026-02-05T05:28:12.823706Z","steps":["trace[1637505304] 'agreement among raft nodes before linearized reading' (duration: 606.978509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:12.823736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:12.216637Z","time spent":"607.094731ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:28:22.101544Z","caller":"traceutil/trace.go:171","msg":"trace[1551004343] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"101.12587ms","start":"2026-02-05T05:28:22.000385Z","end":"2026-02-05T05:28:22.10151Z","steps":["trace[1551004343] 'process raft request' (duration: 100.985038ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:24.802468Z","caller":"traceutil/trace.go:171","msg":"trace[913610927] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"301.1167ms","start":"2026-02-05T05:28:24.501329Z","end":"2026-02-05T05:28:24.802445Z","steps":["trace[913610927] 'process raft request' (duration: 301.001288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:24.802602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:24.501283Z","time spent":"301.253253ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:26.217952Z","caller":"traceutil/trace.go:171","msg":"trace[1585339083] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"444.587048ms","start":"2026-02-05T05:28:25.773353Z","end":"2026-02-05T05:28:26.21794Z","steps":["trace[1585339083] 'read index received' (duration: 444.484776ms)","trace[1585339083] 'applied index is now lower than readState.Index' (duration: 101.292µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:26.218101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.377761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:26.218118Z","caller":"traceutil/trace.go:171","msg":"trace[2134953633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"325.417501ms","start":"2026-02-05T05:28:25.892695Z","end":"2026-02-05T05:28:26.218112Z","steps":["trace[2134953633] 'agreement among raft nodes before linearized reading' (duration: 325.390771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:26.218131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:25.892682Z","time spent":"325.445973ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:26.218003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:25.770212Z","time spent":"447.788457ms","remote":"127.0.0.1:46132","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T05:28:26.21851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.71526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:26.218528Z","caller":"traceutil/trace.go:171","msg":"trace[1699750271] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:855; }","duration":"445.210101ms","start":"2026-02-05T05:28:25.773312Z","end":"2026-02-05T05:28:26.218522Z","steps":["trace[1699750271] 'agreement among raft nodes before linearized reading' (duration: 444.732331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:26.218542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:25.773304Z","time spent":"445.233551ms","remote":"127.0.0.1:46222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:28:26.513503Z","caller":"traceutil/trace.go:171","msg":"trace[1430441953] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"294.219362ms","start":"2026-02-05T05:28:26.219266Z","end":"2026-02-05T05:28:26.513485Z","steps":["trace[1430441953] 'process raft request' (duration: 294.09832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.064099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.472344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:28:27.064205Z","caller":"traceutil/trace.go:171","msg":"trace[2015175961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"103.615707ms","start":"2026-02-05T05:28:26.960569Z","end":"2026-02-05T05:28:27.064185Z","steps":["trace[2015175961] 'range keys from in-memory index tree' (duration: 103.326732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.064402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.087424ms","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-05T05:28:27.064466Z","caller":"traceutil/trace.go:171","msg":"trace[2147299039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"255.185536ms","start":"2026-02-05T05:28:26.809263Z","end":"2026-02-05T05:28:27.064449Z","steps":["trace[2147299039] 'range keys from in-memory index tree' (duration: 254.967832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.064516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.442105ms","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-05T05:28:27.06455Z","caller":"traceutil/trace.go:171","msg":"trace[1545280613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"174.478226ms","start":"2026-02-05T05:28:26.89006Z","end":"2026-02-05T05:28:27.064538Z","steps":["trace[1545280613] 'range keys from in-memory index tree' (duration: 174.355143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.064672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.72492ms","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-05T05:28:27.064696Z","caller":"traceutil/trace.go:171","msg":"trace[1290523408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"167.76601ms","start":"2026-02-05T05:28:26.896921Z","end":"2026-02-05T05:28:27.064687Z","steps":["trace[1290523408] 'range keys from in-memory index tree' (duration: 167.657728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.06487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.024111ms","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-05T05:28:27.064902Z","caller":"traceutil/trace.go:171","msg":"trace[1732547646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"164.067731ms","start":"2026-02-05T05:28:26.900825Z","end":"2026-02-05T05:28:27.064893Z","steps":["trace[1732547646] 'range keys from in-memory index tree' (duration: 163.957679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.841954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.549635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:27.842074Z","caller":"traceutil/trace.go:171","msg":"trace[1831661493] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:886; }","duration":"630.034957ms","start":"2026-02-05T05:28:27.21203Z","end":"2026-02-05T05:28:27.842065Z","steps":["trace[1831661493] 'read index received' (duration: 383.334609ms)","trace[1831661493] 'applied index is now lower than readState.Index' (duration: 246.699788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:27.842118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.093849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:27.842129Z","caller":"traceutil/trace.go:171","msg":"trace[869347592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"630.15896ms","start":"2026-02-05T05:28:27.211966Z","end":"2026-02-05T05:28:27.842125Z","steps":["trace[869347592] 'agreement among raft nodes before linearized reading' (duration: 630.12529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.842145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.211948Z","time spent":"630.193201ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:28:27.842286Z","caller":"traceutil/trace.go:171","msg":"trace[649809849] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"770.069022ms","start":"2026-02-05T05:28:27.072212Z","end":"2026-02-05T05:28:27.842281Z","steps":["trace[649809849] 'process raft request' (duration: 523.14333ms)","trace[649809849] 'compare' (duration: 246.394453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:27.842314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.072177Z","time spent":"770.121713ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:27.842372Z","caller":"traceutil/trace.go:171","msg":"trace[1930698977] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"770.079293ms","start":"2026-02-05T05:28:27.072289Z","end":"2026-02-05T05:28:27.842369Z","steps":["trace[1930698977] 'process raft request' (duration: 769.716705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.842394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.072283Z","time spent":"770.098773ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:27.842457Z","caller":"traceutil/trace.go:171","msg":"trace[1709000598] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"768.798239ms","start":"2026-02-05T05:28:27.073654Z","end":"2026-02-05T05:28:27.842452Z","steps":["trace[1709000598] 'process raft request' (duration: 768.376541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.842478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.073643Z","time spent":"768.823649ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:27.842632Z","caller":"traceutil/trace.go:171","msg":"trace[111367070] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"767.883762ms","start":"2026-02-05T05:28:27.074743Z","end":"2026-02-05T05:28:27.842627Z","steps":["trace[111367070] 'process raft request' (duration: 767.302081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.842657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.074734Z","time spent":"767.909672ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:27.937993Z","caller":"traceutil/trace.go:171","msg":"trace[1379381099] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"636.862434ms","start":"2026-02-05T05:28:27.301117Z","end":"2026-02-05T05:28:27.937979Z","steps":["trace[1379381099] 'process raft request' (duration: 636.586859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.938079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.3011Z","time spent":"636.931276ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:31.984188Z","caller":"traceutil/trace.go:171","msg":"trace[660645143] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"105.565263ms","start":"2026-02-05T05:28:31.878606Z","end":"2026-02-05T05:28:31.984171Z","steps":["trace[660645143] 'process raft request' (duration: 59.768031ms)","trace[660645143] 'compare' (duration: 45.696771ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:31.984299Z","caller":"traceutil/trace.go:171","msg":"trace[1224590463] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"105.651765ms","start":"2026-02-05T05:28:31.878631Z","end":"2026-02-05T05:28:31.984283Z","steps":["trace[1224590463] 'process raft request' (duration: 105.519353ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:36.193932Z","caller":"traceutil/trace.go:171","msg":"trace[1133248167] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"197.426722ms","start":"2026-02-05T05:28:35.99649Z","end":"2026-02-05T05:28:36.193917Z","steps":["trace[1133248167] 'read index received' (duration: 197.306149ms)","trace[1133248167] 'applied index is now lower than readState.Index' (duration: 120.003µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:36.194065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.549704ms","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-05T05:28:36.194083Z","caller":"traceutil/trace.go:171","msg":"trace[1067371105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"197.598114ms","start":"2026-02-05T05:28:35.996479Z","end":"2026-02-05T05:28:36.194077Z","steps":["trace[1067371105] 'agreement among raft nodes before linearized reading' (duration: 197.493753ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:36.194147Z","caller":"traceutil/trace.go:171","msg":"trace[1896970064] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"198.171695ms","start":"2026-02-05T05:28:35.995957Z","end":"2026-02-05T05:28:36.194128Z","steps":["trace[1896970064] 'process raft request' (duration: 197.85635ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:37.653852Z","caller":"traceutil/trace.go:171","msg":"trace[1982354731] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"405.960751ms","start":"2026-02-05T05:28:37.247869Z","end":"2026-02-05T05:28:37.65383Z","steps":["trace[1982354731] 'process raft request' (duration: 405.840519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:37.653983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:37.247861Z","time spent":"406.054822ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:38.428643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410864067,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:28:38.445603Z","caller":"traceutil/trace.go:171","msg":"trace[1072038044] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"555.772927ms","start":"2026-02-05T05:28:37.889816Z","end":"2026-02-05T05:28:38.445589Z","steps":["trace[1072038044] 'process raft request' (duration: 555.680454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:38.44568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:37.889805Z","time spent":"555.825567ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:38.46436Z","caller":"traceutil/trace.go:171","msg":"trace[1176459348] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"536.452577ms","start":"2026-02-05T05:28:37.927881Z","end":"2026-02-05T05:28:38.464334Z","steps":["trace[1176459348] 'read index received' (duration: 518.091155ms)","trace[1176459348] 'applied index is now lower than readState.Index' (duration: 18.361032ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:38.464477Z","caller":"traceutil/trace.go:171","msg":"trace[1888065584] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"342.797415ms","start":"2026-02-05T05:28:38.121675Z","end":"2026-02-05T05:28:38.464472Z","steps":["trace[1888065584] 'process raft request' (duration: 342.617582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:38.464514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:38.121655Z","time spent":"342.833636ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:38.464627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.743542ms","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-05T05:28:38.464643Z","caller":"traceutil/trace.go:171","msg":"trace[916980708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"536.780713ms","start":"2026-02-05T05:28:37.927857Z","end":"2026-02-05T05:28:38.464637Z","steps":["trace[916980708] 'agreement among raft nodes before linearized reading' (duration: 536.720282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:38.464656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:37.927845Z","time spent":"536.807953ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:38.464733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.551666ms","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-05T05:28:38.464747Z","caller":"traceutil/trace.go:171","msg":"trace[1274297393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"267.605747ms","start":"2026-02-05T05:28:38.197138Z","end":"2026-02-05T05:28:38.464744Z","steps":["trace[1274297393] 'agreement among raft nodes before linearized reading' (duration: 267.569116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:38.464844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.808285ms","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-05T05:28:38.464858Z","caller":"traceutil/trace.go:171","msg":"trace[1580837874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"264.829945ms","start":"2026-02-05T05:28:38.200023Z","end":"2026-02-05T05:28:38.464853Z","steps":["trace[1580837874] 'agreement among raft nodes before linearized reading' (duration: 264.794264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:38.604801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.327217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:38.604951Z","caller":"traceutil/trace.go:171","msg":"trace[2030087102] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"134.074183ms","start":"2026-02-05T05:28:38.470858Z","end":"2026-02-05T05:28:38.604932Z","steps":["trace[2030087102] 'process raft request' (duration: 27.537732ms)","trace[2030087102] 'compare' (duration: 106.141374ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:38.60498Z","caller":"traceutil/trace.go:171","msg":"trace[450860338] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"134.081293ms","start":"2026-02-05T05:28:38.470883Z","end":"2026-02-05T05:28:38.604964Z","steps":["trace[450860338] 'process raft request' (duration: 133.998311ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:39.957231Z","caller":"traceutil/trace.go:171","msg":"trace[307414882] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"191.751736ms","start":"2026-02-05T05:28:39.765465Z","end":"2026-02-05T05:28:39.957217Z","steps":["trace[307414882] 'read index received' (duration: 191.636404ms)","trace[307414882] 'applied index is now lower than readState.Index' (duration: 114.862µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:39.957345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.861688ms","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-05T05:28:39.957362Z","caller":"traceutil/trace.go:171","msg":"trace[942579467] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:891; }","duration":"191.916259ms","start":"2026-02-05T05:28:39.76544Z","end":"2026-02-05T05:28:39.957357Z","steps":["trace[942579467] 'agreement among raft nodes before linearized reading' (duration: 191.840748ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:39.957573Z","caller":"traceutil/trace.go:171","msg":"trace[1885543461] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"298.138855ms","start":"2026-02-05T05:28:39.659427Z","end":"2026-02-05T05:28:39.957565Z","steps":["trace[1885543461] 'process raft request' (duration: 297.713687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.204696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.890814ms","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":"warn","ts":"2026-02-05T05:28:41.204756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.715498ms","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-05T05:28:41.204812Z","caller":"traceutil/trace.go:171","msg":"trace[1721920220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"716.063197ms","start":"2026-02-05T05:28:40.488722Z","end":"2026-02-05T05:28:41.204785Z","steps":["trace[1721920220] 'range keys from in-memory index tree' (duration: 715.704021ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:41.20483Z","caller":"traceutil/trace.go:171","msg":"trace[1652786072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"596.833539ms","start":"2026-02-05T05:28:40.607975Z","end":"2026-02-05T05:28:41.204809Z","steps":["trace[1652786072] 'range keys from in-memory index tree' (duration: 596.548375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.204873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:40.488715Z","time spent":"716.146988ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:41.204924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:40.607957Z","time spent":"596.908551ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:41.205078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.836461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T05:28:41.205104Z","caller":"traceutil/trace.go:171","msg":"trace[231058105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"595.881442ms","start":"2026-02-05T05:28:40.609213Z","end":"2026-02-05T05:28:41.205094Z","steps":["trace[231058105] 'range keys from in-memory index tree' (duration: 595.749559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.205129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:40.609203Z","time spent":"595.919933ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:41.205603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.802715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:28:41.205637Z","caller":"traceutil/trace.go:171","msg":"trace[1616801592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"756.925498ms","start":"2026-02-05T05:28:40.448701Z","end":"2026-02-05T05:28:41.205627Z","steps":["trace[1616801592] 'range keys from in-memory index tree' (duration: 756.670954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.205671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:40.448668Z","time spent":"756.99636ms","remote":"127.0.0.1:46354","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-05T05:28:41.703427Z","caller":"traceutil/trace.go:171","msg":"trace[1189716008] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"725.31816ms","start":"2026-02-05T05:28:40.978082Z","end":"2026-02-05T05:28:41.7034Z","steps":["trace[1189716008] 'process raft request' (duration: 725.160667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.703778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:40.978058Z","time spent":"725.637687ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:41.812495Z","caller":"traceutil/trace.go:171","msg":"trace[1746167534] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"599.131953ms","start":"2026-02-05T05:28:41.213339Z","end":"2026-02-05T05:28:41.812471Z","steps":["trace[1746167534] 'process raft request' (duration: 598.885389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.812637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:41.21333Z","time spent":"599.231655ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:41.812806Z","caller":"traceutil/trace.go:171","msg":"trace[648095958] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"600.336655ms","start":"2026-02-05T05:28:41.212458Z","end":"2026-02-05T05:28:41.812794Z","steps":["trace[648095958] 'process raft request' (duration: 534.98947ms)","trace[648095958] 'compare' (duration: 64.257005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:41.812856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:41.21244Z","time spent":"600.388065ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:41.813244Z","caller":"traceutil/trace.go:171","msg":"trace[456386644] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"597.406342ms","start":"2026-02-05T05:28:41.215827Z","end":"2026-02-05T05:28:41.813233Z","steps":["trace[456386644] 'process raft request' (duration: 596.495474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.813298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:41.215797Z","time spent":"597.472282ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:41.813603Z","caller":"traceutil/trace.go:171","msg":"trace[899702066] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"595.142799ms","start":"2026-02-05T05:28:41.21839Z","end":"2026-02-05T05:28:41.813533Z","steps":["trace[899702066] 'process raft request' (duration: 593.967627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:41.813762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:41.218375Z","time spent":"595.294991ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:42.0554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.973299ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T05:28:42.055966Z","caller":"traceutil/trace.go:171","msg":"trace[1467742921] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:922; }","duration":"840.306278ms","start":"2026-02-05T05:28:41.215643Z","end":"2026-02-05T05:28:42.055949Z","steps":["trace[1467742921] 'read index received' (duration: 487.551898ms)","trace[1467742921] 'applied index is now lower than readState.Index' (duration: 352.75228ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:42.057124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.72138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:42.057255Z","caller":"traceutil/trace.go:171","msg":"trace[1993735744] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:896; }","duration":"745.890442ms","start":"2026-02-05T05:28:41.311352Z","end":"2026-02-05T05:28:42.057243Z","steps":["trace[1993735744] 'agreement among raft nodes before linearized reading' (duration: 745.723689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:42.05736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:41.311339Z","time spent":"746.002105ms","remote":"127.0.0.1:46368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:28:42.057121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.45917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:42.057538Z","caller":"traceutil/trace.go:171","msg":"trace[1395428550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"841.900647ms","start":"2026-02-05T05:28:41.21562Z","end":"2026-02-05T05:28:42.057521Z","steps":["trace[1395428550] 'agreement among raft nodes before linearized reading' (duration: 841.445309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:42.057585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:41.215611Z","time spent":"841.963828ms","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:48.15489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.266437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:48.154981Z","caller":"traceutil/trace.go:171","msg":"trace[554715858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"156.40641ms","start":"2026-02-05T05:28:47.998559Z","end":"2026-02-05T05:28:48.154965Z","steps":["trace[554715858] 'range keys from in-memory index tree' (duration: 156.210915ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:52.113104Z","caller":"traceutil/trace.go:171","msg":"trace[841736103] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"102.51381ms","start":"2026-02-05T05:28:52.01057Z","end":"2026-02-05T05:28:52.113083Z","steps":["trace[841736103] 'process raft request' (duration: 68.292103ms)","trace[841736103] 'compare' (duration: 33.999623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:01.753945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.526868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:29:01.754066Z","caller":"traceutil/trace.go:171","msg":"trace[720293335] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"202.33128ms","start":"2026-02-05T05:29:01.551716Z","end":"2026-02-05T05:29:01.754048Z","steps":["trace[720293335] 'process raft request' (duration: 95.610149ms)","trace[720293335] 'compare' (duration: 106.403215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:02.630066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410864336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:29:03.130571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114596410864336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:29:03.088953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053113525s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:29:03.213509Z","caller":"traceutil/trace.go:171","msg":"trace[731933003] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.17772208s","start":"2026-02-05T05:29:02.035762Z","end":"2026-02-05T05:29:03.213485Z","steps":["trace[731933003] 'process raft request' (duration: 1.177583597s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.213847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.035748Z","time spent":"1.177948324s","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:03.947984Z","caller":"traceutil/trace.go:171","msg":"trace[1475949754] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"1.817983599s","start":"2026-02-05T05:29:02.129976Z","end":"2026-02-05T05:29:03.947959Z","steps":["trace[1475949754] 'read index received' (duration: 1.084490808s)","trace[1475949754] 'applied index is now lower than readState.Index' (duration: 733.491721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:03.950897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.782193131s","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-05T05:29:03.951098Z","caller":"traceutil/trace.go:171","msg":"trace[455396528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"1.782422284s","start":"2026-02-05T05:29:02.16866Z","end":"2026-02-05T05:29:03.951082Z","steps":["trace[455396528] 'agreement among raft nodes before linearized reading' (duration: 1.782124539s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.951186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.16865Z","time spent":"1.782522317s","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T05:29:03.951431Z","caller":"traceutil/trace.go:171","msg":"trace[344727918] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.766844166s","start":"2026-02-05T05:29:02.184577Z","end":"2026-02-05T05:29:03.951421Z","steps":["trace[344727918] 'process raft request' (duration: 1.763054014s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.951586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.184526Z","time spent":"1.766987018s","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:03.951867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.439309ms","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-05T05:29:03.951909Z","caller":"traceutil/trace.go:171","msg":"trace[946337007] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1019; }","duration":"592.576212ms","start":"2026-02-05T05:29:03.359322Z","end":"2026-02-05T05:29:03.951899Z","steps":["trace[946337007] 'agreement among raft nodes before linearized reading' (duration: 592.44725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.951997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:03.359307Z","time spent":"592.634683ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:29:03.952132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710762388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T05:29:03.952307Z","caller":"traceutil/trace.go:171","msg":"trace[516872585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"1.710965481s","start":"2026-02-05T05:29:02.241332Z","end":"2026-02-05T05:29:03.952298Z","steps":["trace[516872585] 'agreement among raft nodes before linearized reading' (duration: 1.710558363s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.952403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.241321Z","time spent":"1.711072993s","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:03.952903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73951269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:03.953048Z","caller":"traceutil/trace.go:171","msg":"trace[2019225476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"1.739654182s","start":"2026-02-05T05:29:02.213355Z","end":"2026-02-05T05:29:03.953009Z","steps":["trace[2019225476] 'agreement among raft nodes before linearized reading' (duration: 1.739518069s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.953168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.213343Z","time spent":"1.739796634s","remote":"127.0.0.1:46120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:29:03.953242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.913938ms","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-05T05:29:03.953279Z","caller":"traceutil/trace.go:171","msg":"trace[2144352529] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1019; }","duration":"195.00149ms","start":"2026-02-05T05:29:03.758267Z","end":"2026-02-05T05:29:03.953268Z","steps":["trace[2144352529] 'agreement among raft nodes before linearized reading' (duration: 194.079722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.952329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.822350452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T05:29:03.953631Z","caller":"traceutil/trace.go:171","msg":"trace[1307645850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"1.823670628s","start":"2026-02-05T05:29:02.129934Z","end":"2026-02-05T05:29:03.953604Z","steps":["trace[1307645850] 'agreement among raft nodes before linearized reading' (duration: 1.822271591s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.953714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.129917Z","time spent":"1.82378684s","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:03.951876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.471180292s","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-05T05:29:03.955124Z","caller":"traceutil/trace.go:171","msg":"trace[868239748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1019; }","duration":"1.474466555s","start":"2026-02-05T05:29:02.480649Z","end":"2026-02-05T05:29:03.955115Z","steps":["trace[868239748] 'agreement among raft nodes before linearized reading' (duration: 1.471141471s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.955202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:02.480627Z","time spent":"1.474567166s","remote":"127.0.0.1:46354","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-05T05:29:03.953547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.424808ms","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-05T05:29:03.955374Z","caller":"traceutil/trace.go:171","msg":"trace[1955932676] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1019; }","duration":"402.293144ms","start":"2026-02-05T05:29:03.553073Z","end":"2026-02-05T05:29:03.955366Z","steps":["trace[1955932676] 'agreement among raft nodes before linearized reading' (duration: 400.347307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:03.955437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:03.553049Z","time spent":"402.380415ms","remote":"127.0.0.1:46354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-05T05:29:05.333017Z","caller":"traceutil/trace.go:171","msg":"trace[1850066114] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"113.649824ms","start":"2026-02-05T05:29:05.219347Z","end":"2026-02-05T05:29:05.332997Z","steps":["trace[1850066114] 'process raft request' (duration: 113.235117ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:05.451756Z","caller":"traceutil/trace.go:171","msg":"trace[1777385113] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"113.225677ms","start":"2026-02-05T05:29:05.338505Z","end":"2026-02-05T05:29:05.451731Z","steps":["trace[1777385113] 'process raft request' (duration: 66.161862ms)","trace[1777385113] 'compare' (duration: 46.966273ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:09.11521Z","caller":"traceutil/trace.go:171","msg":"trace[2013787506] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"139.582653ms","start":"2026-02-05T05:29:08.975596Z","end":"2026-02-05T05:29:09.115179Z","steps":["trace[2013787506] 'process raft request' (duration: 138.265597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:10.300248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.429641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:29:10.30036Z","caller":"traceutil/trace.go:171","msg":"trace[1284509135] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"240.991512ms","start":"2026-02-05T05:29:10.059349Z","end":"2026-02-05T05:29:10.300341Z","steps":["trace[1284509135] 'process raft request' (duration: 62.404089ms)","trace[1284509135] 'compare' (duration: 177.094525ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:10.333823Z","caller":"traceutil/trace.go:171","msg":"trace[2000068527] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1084; }","duration":"119.211331ms","start":"2026-02-05T05:29:10.214596Z","end":"2026-02-05T05:29:10.333807Z","steps":["trace[2000068527] 'read index received' (duration: 119.034328ms)","trace[2000068527] 'applied index is now lower than readState.Index' (duration: 176.413µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:10.333934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.330213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:10.334082Z","caller":"traceutil/trace.go:171","msg":"trace[926116669] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"273.941406ms","start":"2026-02-05T05:29:10.06013Z","end":"2026-02-05T05:29:10.334071Z","steps":["trace[926116669] 'process raft request' (duration: 273.546119ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:10.334475Z","caller":"traceutil/trace.go:171","msg":"trace[1863845159] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"271.871536ms","start":"2026-02-05T05:29:10.062596Z","end":"2026-02-05T05:29:10.334467Z","steps":["trace[1863845159] 'process raft request' (duration: 271.157482ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:10.336091Z","caller":"traceutil/trace.go:171","msg":"trace[2077556947] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"272.711522ms","start":"2026-02-05T05:29:10.063371Z","end":"2026-02-05T05:29:10.336082Z","steps":["trace[2077556947] 'process raft request' (duration: 270.416678ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:10.336344Z","caller":"traceutil/trace.go:171","msg":"trace[205873259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"119.402606ms","start":"2026-02-05T05:29:10.214556Z","end":"2026-02-05T05:29:10.333959Z","steps":["trace[205873259] 'agreement among raft nodes before linearized reading' (duration: 119.315705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:11.293226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.77994ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-05T05:29:39.653809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.229412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:29:39.653861Z","caller":"traceutil/trace.go:171","msg":"trace[2074953668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"132.327385ms","start":"2026-02-05T05:29:39.521523Z","end":"2026-02-05T05:29:39.65385Z","steps":["trace[2074953668] 'range keys from in-memory index tree' (duration: 132.062588ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:10.893354Z","caller":"traceutil/trace.go:171","msg":"trace[1113622394] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"105.231313ms","start":"2026-02-05T05:30:10.7881Z","end":"2026-02-05T05:30:10.893331Z","steps":["trace[1113622394] 'process raft request' (duration: 105.09509ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:10.895904Z","caller":"traceutil/trace.go:171","msg":"trace[31689325] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"101.679066ms","start":"2026-02-05T05:30:10.794206Z","end":"2026-02-05T05:30:10.895885Z","steps":["trace[31689325] 'process raft request' (duration: 101.510073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:25.984881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.514201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-05T05:30:25.98567Z","caller":"traceutil/trace.go:171","msg":"trace[1474291901] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1356; }","duration":"146.326946ms","start":"2026-02-05T05:30:25.839324Z","end":"2026-02-05T05:30:25.985651Z","steps":["trace[1474291901] 'range keys from in-memory index tree' (duration: 145.4431ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:26.109243Z","caller":"traceutil/trace.go:171","msg":"trace[2021760504] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"116.965832ms","start":"2026-02-05T05:30:25.992255Z","end":"2026-02-05T05:30:26.109221Z","steps":["trace[2021760504] 'process raft request' (duration: 94.735619ms)","trace[2021760504] 'compare' (duration: 22.160501ms)"],"step_count":2}