{"level":"warn","ts":"2026-02-05T17:43:07.94531Z","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-05T17:43:07.945433Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-05T17:43:07.945501Z","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-05T17:43:07.945509Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-02-05T17:43:07.945525Z","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-05T17:43:07.946124Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-02-05T17:43:07.946196Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-05T17:43:07.982029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"35.62132ms"} {"level":"info","ts":"2026-02-05T17:43:08.102058Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-02-05T17:43:08.102139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:43:08.102158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-02-05T17:43:08.102168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:43:08.102175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-02-05T17:43:08.102205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-02-05T17:43:08.179584Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:43:08.205118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:43:08.240495Z","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-05T17:43:08.255257Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:43:08.255828Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:43:08.256004Z","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-05T17:43:08.256043Z","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-05T17:43:08.256053Z","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-05T17:43:08.268941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-02-05T17:43:08.269159Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-02-05T17:43:08.269247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:43:08.269261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:43:08.269276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-02-05T17:43:08.26929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:43:08.269321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-02-05T17:43:08.269335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-02-05T17:43:08.269343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-02-05T17:43:08.273222Z","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-05T17:43:08.27351Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:43:08.273537Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:43:08.276403Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-02-05T17:43:08.277211Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-02-05T17:43:08.278139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:43:08.278284Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:43:08.278406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:43:08.286678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:43:08.2871Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-02-05T17:43:08.287633Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:43:08.287769Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:43:08.287823Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:43:08.289484Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:43:08.290957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:43:08.291033Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:43:10.922556Z","caller":"traceutil/trace.go:171","msg":"trace[1769877130] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"119.662033ms","start":"2026-02-05T17:43:10.80286Z","end":"2026-02-05T17:43:10.922522Z","steps":["trace[1769877130] 'process raft request' (duration: 67.038007ms)","trace[1769877130] 'compare' (duration: 52.281867ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:10.924113Z","caller":"traceutil/trace.go:171","msg":"trace[972131500] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"116.714458ms","start":"2026-02-05T17:43:10.807383Z","end":"2026-02-05T17:43:10.924098Z","steps":["trace[972131500] 'process raft request' (duration: 115.100078ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:10.924441Z","caller":"traceutil/trace.go:171","msg":"trace[1079483355] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"117.215991ms","start":"2026-02-05T17:43:10.807212Z","end":"2026-02-05T17:43:10.924428Z","steps":["trace[1079483355] 'process raft request' (duration: 115.172409ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:10.924603Z","caller":"traceutil/trace.go:171","msg":"trace[86248774] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"117.345714ms","start":"2026-02-05T17:43:10.807214Z","end":"2026-02-05T17:43:10.924559Z","steps":["trace[86248774] 'process raft request' (duration: 115.231641ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:10.925106Z","caller":"traceutil/trace.go:171","msg":"trace[1948601320] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"117.550659ms","start":"2026-02-05T17:43:10.807542Z","end":"2026-02-05T17:43:10.925093Z","steps":["trace[1948601320] 'process raft request' (duration: 115.010946ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:10.925231Z","caller":"traceutil/trace.go:171","msg":"trace[2081561914] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"117.785225ms","start":"2026-02-05T17:43:10.807435Z","end":"2026-02-05T17:43:10.92522Z","steps":["trace[2081561914] 'process raft request' (duration: 115.085468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:10.925712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.423596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:43:10.925759Z","caller":"traceutil/trace.go:171","msg":"trace[781597132] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:13; }","duration":"113.506098ms","start":"2026-02-05T17:43:10.81224Z","end":"2026-02-05T17:43:10.925746Z","steps":["trace[781597132] 'agreement among raft nodes before linearized reading' (duration: 113.354624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:10.925904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.214531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:43:10.925943Z","caller":"traceutil/trace.go:171","msg":"trace[1986490758] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:13; }","duration":"123.252441ms","start":"2026-02-05T17:43:10.80267Z","end":"2026-02-05T17:43:10.925923Z","steps":["trace[1986490758] 'agreement among raft nodes before linearized reading' (duration: 123.20692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:10.92612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.504318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:43:10.926148Z","caller":"traceutil/trace.go:171","msg":"trace[2125562322] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:13; }","duration":"123.547529ms","start":"2026-02-05T17:43:10.802593Z","end":"2026-02-05T17:43:10.926141Z","steps":["trace[2125562322] 'agreement among raft nodes before linearized reading' (duration: 123.496578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:10.926263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.765295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:43:10.926288Z","caller":"traceutil/trace.go:171","msg":"trace[1553957838] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:0; response_revision:13; }","duration":"123.798905ms","start":"2026-02-05T17:43:10.80248Z","end":"2026-02-05T17:43:10.926279Z","steps":["trace[1553957838] 'agreement among raft nodes before linearized reading' (duration: 123.759294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:14.142596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.470451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:43:14.143148Z","caller":"traceutil/trace.go:171","msg":"trace[222870575] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"128.843141ms","start":"2026-02-05T17:43:14.01429Z","end":"2026-02-05T17:43:14.143133Z","steps":["trace[222870575] 'process raft request' (duration: 16.996405ms)","trace[222870575] 'compare' (duration: 110.352049ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:14.144168Z","caller":"traceutil/trace.go:171","msg":"trace[1590380785] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:254; }","duration":"118.646516ms","start":"2026-02-05T17:43:14.02551Z","end":"2026-02-05T17:43:14.144156Z","steps":["trace[1590380785] 'read index received' (duration: 5.784445ms)","trace[1590380785] 'applied index is now lower than readState.Index' (duration: 112.860881ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:14.144529Z","caller":"traceutil/trace.go:171","msg":"trace[1917018966] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"129.790835ms","start":"2026-02-05T17:43:14.014724Z","end":"2026-02-05T17:43:14.144515Z","steps":["trace[1917018966] 'process raft request' (duration: 129.354905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:14.145888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.40028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:14.146241Z","caller":"traceutil/trace.go:171","msg":"trace[369202636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:250; }","duration":"120.79282ms","start":"2026-02-05T17:43:14.025435Z","end":"2026-02-05T17:43:14.146228Z","steps":["trace[369202636] 'agreement among raft nodes before linearized reading' (duration: 119.121847ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:15.442881Z","caller":"traceutil/trace.go:171","msg":"trace[227319994] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"137.809826ms","start":"2026-02-05T17:43:15.305044Z","end":"2026-02-05T17:43:15.442854Z","steps":["trace[227319994] 'process raft request' (duration: 137.657282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:15.981299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.224653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:43:15.981434Z","caller":"traceutil/trace.go:171","msg":"trace[2033614992] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"424.026013ms","start":"2026-02-05T17:43:15.557388Z","end":"2026-02-05T17:43:15.981414Z","steps":["trace[2033614992] 'read index received' (duration: 2.604625ms)","trace[2033614992] 'applied index is now lower than readState.Index' (duration: 421.419918ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:15.981509Z","caller":"traceutil/trace.go:171","msg":"trace[1564563882] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"527.080169ms","start":"2026-02-05T17:43:15.454416Z","end":"2026-02-05T17:43:15.981497Z","steps":["trace[1564563882] 'process raft request' (duration: 105.569569ms)","trace[1564563882] 'compare' (duration: 421.071459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:15.981561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.454382Z","time spent":"527.155321ms","remote":"127.0.0.1:47204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:43:15.981901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.493114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:15.981954Z","caller":"traceutil/trace.go:171","msg":"trace[812489702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:306; }","duration":"424.603797ms","start":"2026-02-05T17:43:15.557334Z","end":"2026-02-05T17:43:15.981938Z","steps":["trace[812489702] 'agreement among raft nodes before linearized reading' (duration: 424.443023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:15.981978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.557317Z","time spent":"424.657289ms","remote":"127.0.0.1:47028","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-05T17:43:16.311489Z","caller":"traceutil/trace.go:171","msg":"trace[1403512741] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"404.754681ms","start":"2026-02-05T17:43:15.906705Z","end":"2026-02-05T17:43:16.31146Z","steps":["trace[1403512741] 'process raft request' (duration: 404.69015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.311612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.906685Z","time spent":"404.873124ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:43:16.569678Z","caller":"traceutil/trace.go:171","msg":"trace[93555041] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"588.188647ms","start":"2026-02-05T17:43:15.981462Z","end":"2026-02-05T17:43:16.56965Z","steps":["trace[93555041] 'read index received' (duration: 330.877183ms)","trace[93555041] 'applied index is now lower than readState.Index' (duration: 257.310474ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:16.569784Z","caller":"traceutil/trace.go:171","msg":"trace[1785204340] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"661.166752ms","start":"2026-02-05T17:43:15.908603Z","end":"2026-02-05T17:43:16.56977Z","steps":["trace[1785204340] 'process raft request' (duration: 660.986028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.570546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.908573Z","time spent":"661.235494ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:43:16.570868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.947866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-05T17:43:16.570896Z","caller":"traceutil/trace.go:171","msg":"trace[321840436] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:306; }","duration":"715.004808ms","start":"2026-02-05T17:43:15.855885Z","end":"2026-02-05T17:43:16.570889Z","steps":["trace[321840436] 'agreement among raft nodes before linearized reading' (duration: 714.949517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.570916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.855869Z","time spent":"715.042548ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3467,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-05T17:43:16.571044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.018043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:16.571063Z","caller":"traceutil/trace.go:171","msg":"trace[151908787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:306; }","duration":"588.054194ms","start":"2026-02-05T17:43:15.983005Z","end":"2026-02-05T17:43:16.571059Z","steps":["trace[151908787] 'agreement among raft nodes before linearized reading' (duration: 588.022583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.571079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.982993Z","time spent":"588.083305ms","remote":"127.0.0.1:47034","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-05T17:43:16.571414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.876213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5875"} {"level":"info","ts":"2026-02-05T17:43:16.571435Z","caller":"traceutil/trace.go:171","msg":"trace[538206851] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:306; }","duration":"258.920374ms","start":"2026-02-05T17:43:16.31251Z","end":"2026-02-05T17:43:16.57143Z","steps":["trace[538206851] 'agreement among raft nodes before linearized reading' (duration: 258.859053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.571615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.442717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T17:43:16.571636Z","caller":"traceutil/trace.go:171","msg":"trace[2076032328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:306; }","duration":"351.510419ms","start":"2026-02-05T17:43:16.220121Z","end":"2026-02-05T17:43:16.571631Z","steps":["trace[2076032328] 'agreement among raft nodes before linearized reading' (duration: 351.456928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.571653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:16.22011Z","time spent":"351.53952ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:43:16.571824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.673334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:43:16.571844Z","caller":"traceutil/trace.go:171","msg":"trace[1527492146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:306; }","duration":"419.704284ms","start":"2026-02-05T17:43:16.152135Z","end":"2026-02-05T17:43:16.57184Z","steps":["trace[1527492146] 'agreement among raft nodes before linearized reading' (duration: 419.635152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.571861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:16.152132Z","time spent":"419.726695ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:43:16.571976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.520966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:43:16.57201Z","caller":"traceutil/trace.go:171","msg":"trace[154267462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:306; }","duration":"420.580707ms","start":"2026-02-05T17:43:16.151424Z","end":"2026-02-05T17:43:16.572005Z","steps":["trace[154267462] 'agreement among raft nodes before linearized reading' (duration: 420.533756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.57203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:16.151411Z","time spent":"420.613558ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:43:16.572147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.508776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:16.572189Z","caller":"traceutil/trace.go:171","msg":"trace[157489798] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:306; }","duration":"586.562497ms","start":"2026-02-05T17:43:15.9856Z","end":"2026-02-05T17:43:16.572162Z","steps":["trace[157489798] 'agreement among raft nodes before linearized reading' (duration: 586.531097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:16.572209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:15.985582Z","time spent":"586.622508ms","remote":"127.0.0.1:47204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"info","ts":"2026-02-05T17:43:16.810811Z","caller":"traceutil/trace.go:171","msg":"trace[706033763] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"106.222766ms","start":"2026-02-05T17:43:16.704567Z","end":"2026-02-05T17:43:16.81079Z","steps":["trace[706033763] 'process raft request' (duration: 106.176895ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:16.811227Z","caller":"traceutil/trace.go:171","msg":"trace[2070413313] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"107.849757ms","start":"2026-02-05T17:43:16.703359Z","end":"2026-02-05T17:43:16.811209Z","steps":["trace[2070413313] 'process raft request' (duration: 14.858172ms)","trace[2070413313] 'compare' (duration: 92.40422ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:16.981728Z","caller":"traceutil/trace.go:171","msg":"trace[2146009489] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"152.603486ms","start":"2026-02-05T17:43:16.829102Z","end":"2026-02-05T17:43:16.981706Z","steps":["trace[2146009489] 'process raft request' (duration: 108.336249ms)","trace[2146009489] 'compare' (duration: 43.782025ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:16.982236Z","caller":"traceutil/trace.go:171","msg":"trace[1756007805] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"152.870103ms","start":"2026-02-05T17:43:16.829357Z","end":"2026-02-05T17:43:16.982227Z","steps":["trace[1756007805] 'process raft request' (duration: 151.966921ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:18.193635Z","caller":"traceutil/trace.go:171","msg":"trace[1161521338] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"486.143805ms","start":"2026-02-05T17:43:17.707457Z","end":"2026-02-05T17:43:18.193601Z","steps":["trace[1161521338] 'process raft request' (duration: 485.325335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:18.194177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:17.707425Z","time spent":"486.6887ms","remote":"127.0.0.1:47204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:43:23.547328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.627414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-05T17:43:23.54736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.991323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-05T17:43:23.547433Z","caller":"traceutil/trace.go:171","msg":"trace[491191042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"459.816388ms","start":"2026-02-05T17:43:23.087592Z","end":"2026-02-05T17:43:23.547409Z","steps":["trace[491191042] 'range keys from in-memory index tree' (duration: 459.409418ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:23.54747Z","caller":"traceutil/trace.go:171","msg":"trace[1889251311] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:396; }","duration":"584.187397ms","start":"2026-02-05T17:43:22.963261Z","end":"2026-02-05T17:43:23.547448Z","steps":["trace[1889251311] 'range keys from in-memory index tree' (duration: 583.828348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:23.547493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:23.08757Z","time spent":"459.910501ms","remote":"127.0.0.1:47268","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-05T17:43:23.547519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:22.963231Z","time spent":"584.27689ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7260,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-05T17:43:23.547782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.248554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:43:23.54785Z","caller":"traceutil/trace.go:171","msg":"trace[2049658347] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:396; }","duration":"533.315186ms","start":"2026-02-05T17:43:23.014525Z","end":"2026-02-05T17:43:23.54784Z","steps":["trace[2049658347] 'range keys from in-memory index tree' (duration: 533.111951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:23.547878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:23.01442Z","time spent":"533.45129ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-02-05T17:43:23.724357Z","caller":"traceutil/trace.go:171","msg":"trace[809228431] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:409; }","duration":"148.145636ms","start":"2026-02-05T17:43:23.576183Z","end":"2026-02-05T17:43:23.724329Z","steps":["trace[809228431] 'read index received' (duration: 148.135875ms)","trace[809228431] 'applied index is now lower than readState.Index' (duration: 8.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:23.724501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.285049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:23.724521Z","caller":"traceutil/trace.go:171","msg":"trace[1439599972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"148.33564ms","start":"2026-02-05T17:43:23.57618Z","end":"2026-02-05T17:43:23.724515Z","steps":["trace[1439599972] 'agreement among raft nodes before linearized reading' (duration: 148.246798ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:23.901676Z","caller":"traceutil/trace.go:171","msg":"trace[1894520350] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"323.257603ms","start":"2026-02-05T17:43:23.578391Z","end":"2026-02-05T17:43:23.901649Z","steps":["trace[1894520350] 'process raft request' (duration: 323.208302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:23.901831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:23.578386Z","time spent":"323.361836ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:43:23.902001Z","caller":"traceutil/trace.go:171","msg":"trace[1169729746] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"325.702595ms","start":"2026-02-05T17:43:23.576259Z","end":"2026-02-05T17:43:23.901962Z","steps":["trace[1169729746] 'process raft request' (duration: 325.188602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:23.902182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:23.576249Z","time spent":"325.831088ms","remote":"127.0.0.1:47268","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-05T17:43:23.905288Z","caller":"traceutil/trace.go:171","msg":"trace[515333099] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"179.423947ms","start":"2026-02-05T17:43:23.725854Z","end":"2026-02-05T17:43:23.905278Z","steps":["trace[515333099] 'process raft request' (duration: 179.378255ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:25.490522Z","caller":"traceutil/trace.go:171","msg":"trace[686305277] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"101.282363ms","start":"2026-02-05T17:43:25.389219Z","end":"2026-02-05T17:43:25.490501Z","steps":["trace[686305277] 'process raft request' (duration: 101.17069ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:26.187369Z","caller":"traceutil/trace.go:171","msg":"trace[2076392867] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"273.560671ms","start":"2026-02-05T17:43:25.913785Z","end":"2026-02-05T17:43:26.187345Z","steps":["trace[2076392867] 'read index received' (duration: 273.270273ms)","trace[2076392867] 'applied index is now lower than readState.Index' (duration: 289.498µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:26.187478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.674663ms","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-05T17:43:26.187503Z","caller":"traceutil/trace.go:171","msg":"trace[815972477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"273.754645ms","start":"2026-02-05T17:43:25.913739Z","end":"2026-02-05T17:43:26.187493Z","steps":["trace[815972477] 'agreement among raft nodes before linearized reading' (duration: 273.688254ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:26.187718Z","caller":"traceutil/trace.go:171","msg":"trace[1603072219] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"316.967315ms","start":"2026-02-05T17:43:25.870731Z","end":"2026-02-05T17:43:26.187698Z","steps":["trace[1603072219] 'process raft request' (duration: 316.410872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:26.189856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:25.870717Z","time spent":"317.041487ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:43:29.017795Z","caller":"traceutil/trace.go:171","msg":"trace[199145405] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"198.215876ms","start":"2026-02-05T17:43:28.81954Z","end":"2026-02-05T17:43:29.017756Z","steps":["trace[199145405] 'read index received' (duration: 197.951499ms)","trace[199145405] 'applied index is now lower than readState.Index' (duration: 263.687µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:29.018103Z","caller":"traceutil/trace.go:171","msg":"trace[1900942233] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"213.298413ms","start":"2026-02-05T17:43:28.804796Z","end":"2026-02-05T17:43:29.018094Z","steps":["trace[1900942233] 'process raft request' (duration: 212.77634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:29.018363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.79947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:43:29.018391Z","caller":"traceutil/trace.go:171","msg":"trace[1032314801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"198.913363ms","start":"2026-02-05T17:43:28.819468Z","end":"2026-02-05T17:43:29.018381Z","steps":["trace[1032314801] 'agreement among raft nodes before linearized reading' (duration: 198.798739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:29.018485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.064378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:43:29.018502Z","caller":"traceutil/trace.go:171","msg":"trace[1926889979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"197.104609ms","start":"2026-02-05T17:43:28.821392Z","end":"2026-02-05T17:43:29.018497Z","steps":["trace[1926889979] 'agreement among raft nodes before linearized reading' (duration: 197.072108ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:31.63523Z","caller":"traceutil/trace.go:171","msg":"trace[1359809077] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"224.447182ms","start":"2026-02-05T17:43:31.410757Z","end":"2026-02-05T17:43:31.635204Z","steps":["trace[1359809077] 'process raft request' (duration: 223.841656ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:35.283505Z","caller":"traceutil/trace.go:171","msg":"trace[674213226] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"981.012669ms","start":"2026-02-05T17:43:34.302456Z","end":"2026-02-05T17:43:35.283469Z","steps":["trace[674213226] 'process raft request' (duration: 980.874746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:35.283661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:34.302445Z","time spent":"981.129262ms","remote":"127.0.0.1:47268","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-05T17:43:35.28407Z","caller":"traceutil/trace.go:171","msg":"trace[1555707310] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:477; }","duration":"227.814236ms","start":"2026-02-05T17:43:35.056248Z","end":"2026-02-05T17:43:35.284062Z","steps":["trace[1555707310] 'read index received' (duration: 227.810576ms)","trace[1555707310] 'applied index is now lower than readState.Index' (duration: 3.27µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:35.284193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.364225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:43:35.284224Z","caller":"traceutil/trace.go:171","msg":"trace[65397959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:460; }","duration":"197.451146ms","start":"2026-02-05T17:43:35.086765Z","end":"2026-02-05T17:43:35.284216Z","steps":["trace[65397959] 'agreement among raft nodes before linearized reading' (duration: 197.350414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:35.284433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.189385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T17:43:35.284472Z","caller":"traceutil/trace.go:171","msg":"trace[695708485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:460; }","duration":"228.268747ms","start":"2026-02-05T17:43:35.056194Z","end":"2026-02-05T17:43:35.284463Z","steps":["trace[695708485] 'agreement among raft nodes before linearized reading' (duration: 228.205985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:35.284617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.391035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:43:35.284642Z","caller":"traceutil/trace.go:171","msg":"trace[1443437692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:460; }","duration":"195.430436ms","start":"2026-02-05T17:43:35.089205Z","end":"2026-02-05T17:43:35.284636Z","steps":["trace[1443437692] 'agreement among raft nodes before linearized reading' (duration: 195.388395ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:39.524486Z","caller":"traceutil/trace.go:171","msg":"trace[620172372] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"146.003611ms","start":"2026-02-05T17:43:39.378449Z","end":"2026-02-05T17:43:39.524453Z","steps":["trace[620172372] 'process raft request' (duration: 145.745365ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:39.526557Z","caller":"traceutil/trace.go:171","msg":"trace[65562545] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"145.517238ms","start":"2026-02-05T17:43:39.381015Z","end":"2026-02-05T17:43:39.526533Z","steps":["trace[65562545] 'process raft request' (duration: 145.356674ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:39.526764Z","caller":"traceutil/trace.go:171","msg":"trace[1268156044] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"145.134209ms","start":"2026-02-05T17:43:39.381622Z","end":"2026-02-05T17:43:39.526756Z","steps":["trace[1268156044] 'process raft request' (duration: 144.872432ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:41.490071Z","caller":"traceutil/trace.go:171","msg":"trace[249417046] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"108.530082ms","start":"2026-02-05T17:43:41.381512Z","end":"2026-02-05T17:43:41.490042Z","steps":["trace[249417046] 'process raft request' (duration: 108.332587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.039871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610909210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:43:42.540513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610909210,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:43:42.657964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.121764838s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:43:42.660512Z","caller":"traceutil/trace.go:171","msg":"trace[504344701] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.124273711s","start":"2026-02-05T17:43:41.536122Z","end":"2026-02-05T17:43:42.660396Z","steps":["trace[504344701] 'process raft request' (duration: 1.122103846s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.66067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:41.536109Z","time spent":"1.124471556s","remote":"127.0.0.1:47268","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-05T17:43:42.858794Z","caller":"traceutil/trace.go:171","msg":"trace[339729304] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:491; }","duration":"1.322501067s","start":"2026-02-05T17:43:41.536262Z","end":"2026-02-05T17:43:42.858763Z","steps":["trace[339729304] 'read index received' (duration: 1.121976623s)","trace[339729304] 'applied index is now lower than readState.Index' (duration: 200.514854ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:42.858939Z","caller":"traceutil/trace.go:171","msg":"trace[1073273337] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.10740216s","start":"2026-02-05T17:43:41.751525Z","end":"2026-02-05T17:43:42.858928Z","steps":["trace[1073273337] 'process raft request' (duration: 1.107043721s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.859243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322929748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:43:42.859324Z","caller":"traceutil/trace.go:171","msg":"trace[1215157850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"1.32302556s","start":"2026-02-05T17:43:41.536278Z","end":"2026-02-05T17:43:42.859303Z","steps":["trace[1215157850] 'agreement among raft nodes before linearized reading' (duration: 1.322877147s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.859335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:41.751493Z","time spent":"1.107680167s","remote":"127.0.0.1:47268","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-05T17:43:42.85937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:41.536274Z","time spent":"1.323086272s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:43:42.859529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.296993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:42.859569Z","caller":"traceutil/trace.go:171","msg":"trace[190276576] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:475; }","duration":"169.375365ms","start":"2026-02-05T17:43:42.690179Z","end":"2026-02-05T17:43:42.859555Z","steps":["trace[190276576] 'agreement among raft nodes before linearized reading' (duration: 169.314543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.859586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298962628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:42.859616Z","caller":"traceutil/trace.go:171","msg":"trace[944156186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"1.29903349s","start":"2026-02-05T17:43:41.560573Z","end":"2026-02-05T17:43:42.859606Z","steps":["trace[944156186] 'agreement among raft nodes before linearized reading' (duration: 1.298987139s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.859669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:41.560549Z","time spent":"1.299089432s","remote":"127.0.0.1:47034","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-05T17:43:42.85992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323647345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:43:42.859957Z","caller":"traceutil/trace.go:171","msg":"trace[1518987923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"1.323698316s","start":"2026-02-05T17:43:41.536249Z","end":"2026-02-05T17:43:42.859947Z","steps":["trace[1518987923] 'agreement among raft nodes before linearized reading' (duration: 1.323628025s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:42.85999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:41.536244Z","time spent":"1.323738599s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:43:43.387942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.878621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:43.388049Z","caller":"traceutil/trace.go:171","msg":"trace[867287132] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:477; }","duration":"412.071935ms","start":"2026-02-05T17:43:42.975951Z","end":"2026-02-05T17:43:43.388023Z","steps":["trace[867287132] 'range keys from in-memory index tree' (duration: 411.800278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:43.388111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:42.97593Z","time spent":"412.169417ms","remote":"127.0.0.1:47094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-05T17:43:43.388157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.385583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:43:43.388253Z","caller":"traceutil/trace.go:171","msg":"trace[476730543] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:477; }","duration":"372.538907ms","start":"2026-02-05T17:43:43.015697Z","end":"2026-02-05T17:43:43.388235Z","steps":["trace[476730543] 'range keys from in-memory index tree' (duration: 372.28216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:43.388304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:43.015682Z","time spent":"372.607848ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-02-05T17:43:43.703586Z","caller":"traceutil/trace.go:171","msg":"trace[1757067651] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"144.640447ms","start":"2026-02-05T17:43:43.558925Z","end":"2026-02-05T17:43:43.703566Z","steps":["trace[1757067651] 'process raft request' (duration: 144.563245ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:43.703931Z","caller":"traceutil/trace.go:171","msg":"trace[1884035422] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"144.984236ms","start":"2026-02-05T17:43:43.558871Z","end":"2026-02-05T17:43:43.703855Z","steps":["trace[1884035422] 'process raft request' (duration: 129.250582ms)","trace[1884035422] 'compare' (duration: 13.807436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:47.613639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.649257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:43:47.613748Z","caller":"traceutil/trace.go:171","msg":"trace[1018320647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"702.809991ms","start":"2026-02-05T17:43:46.910917Z","end":"2026-02-05T17:43:47.613727Z","steps":["trace[1018320647] 'range keys from in-memory index tree' (duration: 702.570305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:47.613786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:46.910895Z","time spent":"702.882063ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:43:47.61398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.976391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:43:47.614009Z","caller":"traceutil/trace.go:171","msg":"trace[1358326429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"692.027672ms","start":"2026-02-05T17:43:46.921972Z","end":"2026-02-05T17:43:47.614Z","steps":["trace[1358326429] 'range keys from in-memory index tree' (duration: 691.93656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:47.614033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:46.921962Z","time spent":"692.065373ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:43:48.678482Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051955574s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:43:48.679585Z","caller":"traceutil/trace.go:171","msg":"trace[536117898] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.053160373s","start":"2026-02-05T17:43:47.626398Z","end":"2026-02-05T17:43:48.679558Z","steps":["trace[536117898] 'process raft request' (duration: 1.053015709s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:48.679734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:47.626365Z","time spent":"1.053296996s","remote":"127.0.0.1:47268","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-05T17:43:48.697602Z","caller":"traceutil/trace.go:171","msg":"trace[388187835] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.069223404s","start":"2026-02-05T17:43:47.628316Z","end":"2026-02-05T17:43:48.697539Z","steps":["trace[388187835] 'process raft request' (duration: 1.068993688s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:48.697793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:47.628299Z","time spent":"1.069393168s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:43:48.698135Z","caller":"traceutil/trace.go:171","msg":"trace[450573554] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.069740148s","start":"2026-02-05T17:43:47.628389Z","end":"2026-02-05T17:43:48.69813Z","steps":["trace[450573554] 'process raft request' (duration: 1.06905462s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:48.698169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:47.628384Z","time spent":"1.069768488s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:43:53.224956Z","caller":"traceutil/trace.go:171","msg":"trace[484559273] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"368.328649ms","start":"2026-02-05T17:43:52.856578Z","end":"2026-02-05T17:43:53.224906Z","steps":["trace[484559273] 'process raft request' (duration: 351.382925ms)","trace[484559273] 'compare' (duration: 16.057352ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:53.225047Z","caller":"traceutil/trace.go:171","msg":"trace[1527897619] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"208.361179ms","start":"2026-02-05T17:43:53.016676Z","end":"2026-02-05T17:43:53.225038Z","steps":["trace[1527897619] 'read index received' (duration: 191.300693ms)","trace[1527897619] 'applied index is now lower than readState.Index' (duration: 17.059476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:53.225167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:52.856565Z","time spent":"368.492804ms","remote":"127.0.0.1:47268","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-05T17:43:53.225241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.557695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:43:53.22527Z","caller":"traceutil/trace.go:171","msg":"trace[432917432] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:533; }","duration":"208.655918ms","start":"2026-02-05T17:43:53.016605Z","end":"2026-02-05T17:43:53.225261Z","steps":["trace[432917432] 'agreement among raft nodes before linearized reading' (duration: 208.482403ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:53.224837Z","caller":"traceutil/trace.go:171","msg":"trace[918381716] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"178.497333ms","start":"2026-02-05T17:43:53.046313Z","end":"2026-02-05T17:43:53.22481Z","steps":["trace[918381716] 'process raft request' (duration: 178.445991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:57.741938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610909371,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:43:58.148787Z","caller":"traceutil/trace.go:171","msg":"trace[1848829607] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"907.35254ms","start":"2026-02-05T17:43:57.241375Z","end":"2026-02-05T17:43:58.148727Z","steps":["trace[1848829607] 'read index received' (duration: 907.096073ms)","trace[1848829607] 'applied index is now lower than readState.Index' (duration: 255.657µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:43:58.148917Z","caller":"traceutil/trace.go:171","msg":"trace[1190688008] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.026339375s","start":"2026-02-05T17:43:57.122565Z","end":"2026-02-05T17:43:58.148904Z","steps":["trace[1190688008] 'process raft request' (duration: 1.026007127s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:58.14906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:57.122553Z","time spent":"1.026396596s","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:43:58.149272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.908404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T17:43:58.149309Z","caller":"traceutil/trace.go:171","msg":"trace[1023942583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"907.991746ms","start":"2026-02-05T17:43:57.241305Z","end":"2026-02-05T17:43:58.149296Z","steps":["trace[1023942583] 'agreement among raft nodes before linearized reading' (duration: 907.922304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:58.149342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:57.241254Z","time spent":"908.081428ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:43:58.14949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.957126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:43:58.149572Z","caller":"traceutil/trace.go:171","msg":"trace[1996605329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"585.093459ms","start":"2026-02-05T17:43:57.564458Z","end":"2026-02-05T17:43:58.149551Z","steps":["trace[1996605329] 'agreement among raft nodes before linearized reading' (duration: 584.972256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:43:58.149617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:57.564431Z","time spent":"585.177191ms","remote":"127.0.0.1:47034","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-05T17:43:58.52226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.8031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:43:58.522389Z","caller":"traceutil/trace.go:171","msg":"trace[1713117993] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"356.693299ms","start":"2026-02-05T17:43:58.165673Z","end":"2026-02-05T17:43:58.522366Z","steps":["trace[1713117993] 'process raft request' (duration: 251.662713ms)","trace[1713117993] 'compare' (duration: 104.616166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:43:58.522483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:43:58.165647Z","time spent":"356.798491ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:44:00.708303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.065793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T17:44:00.708401Z","caller":"traceutil/trace.go:171","msg":"trace[1755201017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"181.232817ms","start":"2026-02-05T17:44:00.527145Z","end":"2026-02-05T17:44:00.708378Z","steps":["trace[1755201017] 'range keys from in-memory index tree' (duration: 180.807276ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:05.704365Z","caller":"traceutil/trace.go:171","msg":"trace[473928753] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"308.413534ms","start":"2026-02-05T17:44:05.395895Z","end":"2026-02-05T17:44:05.704309Z","steps":["trace[473928753] 'process raft request' (duration: 308.23471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:05.704545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:05.39574Z","time spent":"308.707801ms","remote":"127.0.0.1:47182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:44:05.705291Z","caller":"traceutil/trace.go:171","msg":"trace[1654609653] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:658; }","duration":"233.73341ms","start":"2026-02-05T17:44:05.47152Z","end":"2026-02-05T17:44:05.705254Z","steps":["trace[1654609653] 'read index received' (duration: 233.721039ms)","trace[1654609653] 'applied index is now lower than readState.Index' (duration: 10.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:05.705761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.18575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-05T17:44:05.70586Z","caller":"traceutil/trace.go:171","msg":"trace[1245437203] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:634; }","duration":"234.383044ms","start":"2026-02-05T17:44:05.471465Z","end":"2026-02-05T17:44:05.705848Z","steps":["trace[1245437203] 'agreement among raft nodes before linearized reading' (duration: 233.981055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:05.733163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.096308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:05.733243Z","caller":"traceutil/trace.go:171","msg":"trace[589865511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"173.243912ms","start":"2026-02-05T17:44:05.559971Z","end":"2026-02-05T17:44:05.733215Z","steps":["trace[589865511] 'agreement among raft nodes before linearized reading' (duration: 173.103688ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:05.733452Z","caller":"traceutil/trace.go:171","msg":"trace[1047351685] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"217.038494ms","start":"2026-02-05T17:44:05.516407Z","end":"2026-02-05T17:44:05.733445Z","steps":["trace[1047351685] 'process raft request' (duration: 216.383358ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:11.389081Z","caller":"traceutil/trace.go:171","msg":"trace[329703608] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"522.94869ms","start":"2026-02-05T17:44:10.866108Z","end":"2026-02-05T17:44:11.389056Z","steps":["trace[329703608] 'process raft request' (duration: 522.825097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:11.389211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:10.866088Z","time spent":"523.051903ms","remote":"127.0.0.1:47268","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-05T17:44:13.073161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022726956s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:44:13.073239Z","caller":"traceutil/trace.go:171","msg":"trace[116865111] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"2.132765879s","start":"2026-02-05T17:44:10.94046Z","end":"2026-02-05T17:44:13.073226Z","steps":["trace[116865111] 'read index received' (duration: 449.026183ms)","trace[116865111] 'applied index is now lower than readState.Index' (duration: 1.683738796s)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:44:13.07497Z","caller":"traceutil/trace.go:171","msg":"trace[1512420865] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"2.206539983s","start":"2026-02-05T17:44:10.868417Z","end":"2026-02-05T17:44:13.074957Z","steps":["trace[1512420865] 'process raft request' (duration: 1.181946234s)","trace[1512420865] 'compare' (duration: 1.021968528s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:13.075673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:10.868406Z","time spent":"2.207214439s","remote":"127.0.0.1:47268","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-05T17:44:13.076138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135673766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:44:13.077409Z","caller":"traceutil/trace.go:171","msg":"trace[465890184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"2.135730038s","start":"2026-02-05T17:44:10.940431Z","end":"2026-02-05T17:44:13.076161Z","steps":["trace[465890184] 'agreement among raft nodes before linearized reading' (duration: 2.135510523s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:13.077447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:10.940389Z","time spent":"2.137048708s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:44:13.077811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.136155466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:44:13.077839Z","caller":"traceutil/trace.go:171","msg":"trace[361322495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"2.136231378s","start":"2026-02-05T17:44:10.9416Z","end":"2026-02-05T17:44:13.077832Z","steps":["trace[361322495] 'agreement among raft nodes before linearized reading' (duration: 2.136121685s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:13.077929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:10.941596Z","time spent":"2.13632666s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:44:13.079614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676260433s","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-05T17:44:13.079661Z","caller":"traceutil/trace.go:171","msg":"trace[506236800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"1.676317684s","start":"2026-02-05T17:44:11.403335Z","end":"2026-02-05T17:44:13.079653Z","steps":["trace[506236800] 'agreement among raft nodes before linearized reading' (duration: 1.676226272s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:13.079682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:11.40333Z","time spent":"1.676346725s","remote":"127.0.0.1:47268","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-05T17:44:13.079872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519482861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:13.079898Z","caller":"traceutil/trace.go:171","msg":"trace[1996818747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"1.519545833s","start":"2026-02-05T17:44:11.560345Z","end":"2026-02-05T17:44:13.079891Z","steps":["trace[1996818747] 'agreement among raft nodes before linearized reading' (duration: 1.519503592s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:13.079917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:11.560291Z","time spent":"1.519621564s","remote":"127.0.0.1:47034","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-05T17:44:13.079998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677629845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:13.080098Z","caller":"traceutil/trace.go:171","msg":"trace[335346079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"1.677778018s","start":"2026-02-05T17:44:11.4023Z","end":"2026-02-05T17:44:13.080078Z","steps":["trace[335346079] 'agreement among raft nodes before linearized reading' (duration: 1.677572823s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:13.080141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:11.402286Z","time spent":"1.67784846s","remote":"127.0.0.1:47028","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-05T17:44:21.580284Z","caller":"traceutil/trace.go:171","msg":"trace[244454584] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"118.090907ms","start":"2026-02-05T17:44:21.462169Z","end":"2026-02-05T17:44:21.58026Z","steps":["trace[244454584] 'process raft request' (duration: 117.600646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.571162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610909783,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:44:23.724095Z","caller":"traceutil/trace.go:171","msg":"trace[1734075561] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"653.648561ms","start":"2026-02-05T17:44:23.070373Z","end":"2026-02-05T17:44:23.724022Z","steps":["trace[1734075561] 'read index received' (duration: 653.394115ms)","trace[1734075561] 'applied index is now lower than readState.Index' (duration: 253.206µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:23.724439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.019271Z","time spent":"704.961356ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T17:44:23.724836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.430469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:23.724868Z","caller":"traceutil/trace.go:171","msg":"trace[1085641001] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:744; }","duration":"654.513521ms","start":"2026-02-05T17:44:23.070344Z","end":"2026-02-05T17:44:23.724857Z","steps":["trace[1085641001] 'agreement among raft nodes before linearized reading' (duration: 654.422019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.724892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.070328Z","time spent":"654.557852ms","remote":"127.0.0.1:47478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:44:23.725113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.914235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T17:44:23.725135Z","caller":"traceutil/trace.go:171","msg":"trace[275050517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"543.961815ms","start":"2026-02-05T17:44:23.181165Z","end":"2026-02-05T17:44:23.725127Z","steps":["trace[275050517] 'agreement among raft nodes before linearized reading' (duration: 543.841253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.725156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.181146Z","time spent":"544.004137ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:44:23.725346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.378693ms","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-05T17:44:23.725398Z","caller":"traceutil/trace.go:171","msg":"trace[2117621360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"140.441504ms","start":"2026-02-05T17:44:23.584948Z","end":"2026-02-05T17:44:23.72539Z","steps":["trace[2117621360] 'agreement among raft nodes before linearized reading' (duration: 140.373003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.725505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.540871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:23.725522Z","caller":"traceutil/trace.go:171","msg":"trace[625301510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"164.597982ms","start":"2026-02-05T17:44:23.560919Z","end":"2026-02-05T17:44:23.725517Z","steps":["trace[625301510] 'agreement among raft nodes before linearized reading' (duration: 164.568832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.725625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.489945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:23.725645Z","caller":"traceutil/trace.go:171","msg":"trace[326384659] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:744; }","duration":"215.094939ms","start":"2026-02-05T17:44:23.510541Z","end":"2026-02-05T17:44:23.725636Z","steps":["trace[326384659] 'agreement among raft nodes before linearized reading' (duration: 215.063408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.726274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.13836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:44:23.728257Z","caller":"traceutil/trace.go:171","msg":"trace[2031069866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"514.119806ms","start":"2026-02-05T17:44:23.214126Z","end":"2026-02-05T17:44:23.728246Z","steps":["trace[2031069866] 'agreement among raft nodes before linearized reading' (duration: 512.077449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.728305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.214106Z","time spent":"514.188388ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:44:23.726332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.597259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:44:23.728406Z","caller":"traceutil/trace.go:171","msg":"trace[582272310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"526.675837ms","start":"2026-02-05T17:44:23.201722Z","end":"2026-02-05T17:44:23.728398Z","steps":["trace[582272310] 'agreement among raft nodes before linearized reading' (duration: 524.570498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.72843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.201716Z","time spent":"526.707127ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:44:23.726377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.764034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:44:23.728572Z","caller":"traceutil/trace.go:171","msg":"trace[2139075419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"530.960055ms","start":"2026-02-05T17:44:23.197591Z","end":"2026-02-05T17:44:23.728551Z","steps":["trace[2139075419] 'agreement among raft nodes before linearized reading' (duration: 528.752314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:23.728597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.197581Z","time spent":"531.008946ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:44:24.298417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.77818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:24.298537Z","caller":"traceutil/trace.go:171","msg":"trace[83441175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"558.924262ms","start":"2026-02-05T17:44:23.739583Z","end":"2026-02-05T17:44:24.298507Z","steps":["trace[83441175] 'agreement among raft nodes before linearized reading' (duration: 17.584905ms)","trace[83441175] 'range keys from in-memory index tree' (duration: 541.210703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:24.29859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.739567Z","time spent":"559.014615ms","remote":"127.0.0.1:47034","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-05T17:44:24.299694Z","caller":"traceutil/trace.go:171","msg":"trace[1084496513] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"559.568177ms","start":"2026-02-05T17:44:23.740113Z","end":"2026-02-05T17:44:24.299681Z","steps":["trace[1084496513] 'process raft request' (duration: 559.527226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:24.302442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.74011Z","time spent":"559.626138ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:44:24.302634Z","caller":"traceutil/trace.go:171","msg":"trace[522831661] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"562.72343ms","start":"2026-02-05T17:44:23.739903Z","end":"2026-02-05T17:44:24.302626Z","steps":["trace[522831661] 'process raft request' (duration: 558.207796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:24.30267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.739892Z","time spent":"562.75436ms","remote":"127.0.0.1:47268","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-05T17:44:24.302975Z","caller":"traceutil/trace.go:171","msg":"trace[2087865277] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"562.919324ms","start":"2026-02-05T17:44:23.740033Z","end":"2026-02-05T17:44:24.302952Z","steps":["trace[2087865277] 'process raft request' (duration: 559.514376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:24.303096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.740029Z","time spent":"562.995356ms","remote":"127.0.0.1:47268","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-05T17:44:24.303217Z","caller":"traceutil/trace.go:171","msg":"trace[708976710] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"563.229791ms","start":"2026-02-05T17:44:23.739977Z","end":"2026-02-05T17:44:24.303207Z","steps":["trace[708976710] 'process raft request' (duration: 559.474414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:24.303254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.739973Z","time spent":"563.258382ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:44:24.303302Z","caller":"traceutil/trace.go:171","msg":"trace[1965570058] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"563.286113ms","start":"2026-02-05T17:44:23.740006Z","end":"2026-02-05T17:44:24.303292Z","steps":["trace[1965570058] 'process raft request' (duration: 559.507746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:24.303327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.740002Z","time spent":"563.309493ms","remote":"127.0.0.1:47268","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-05T17:44:24.601495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.421919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:44:24.601639Z","caller":"traceutil/trace.go:171","msg":"trace[1859082843] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:775; }","duration":"397.144674ms","start":"2026-02-05T17:44:24.204481Z","end":"2026-02-05T17:44:24.601626Z","steps":["trace[1859082843] 'read index received' (duration: 93.575422ms)","trace[1859082843] 'applied index is now lower than readState.Index' (duration: 303.568512ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:44:24.601821Z","caller":"traceutil/trace.go:171","msg":"trace[24050862] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"844.751635ms","start":"2026-02-05T17:44:23.757059Z","end":"2026-02-05T17:44:24.60181Z","steps":["trace[24050862] 'process raft request' (duration: 724.948147ms)","trace[24050862] 'compare' (duration: 119.00485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:24.60191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.757036Z","time spent":"844.810717ms","remote":"127.0.0.1:47268","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-05T17:44:24.602442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.904762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:24.602524Z","caller":"traceutil/trace.go:171","msg":"trace[586394041] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:751; }","duration":"398.611668ms","start":"2026-02-05T17:44:24.203889Z","end":"2026-02-05T17:44:24.6025Z","steps":["trace[586394041] 'agreement among raft nodes before linearized reading' (duration: 398.428433ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:24.602543Z","caller":"traceutil/trace.go:171","msg":"trace[458365322] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"845.392139ms","start":"2026-02-05T17:44:23.75714Z","end":"2026-02-05T17:44:24.602532Z","steps":["trace[458365322] 'process raft request' (duration: 844.444248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:24.602559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:24.203872Z","time spent":"398.679799ms","remote":"127.0.0.1:47376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:44:24.602599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:23.757134Z","time spent":"845.43452ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:44:24.602837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.103194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T17:44:24.602865Z","caller":"traceutil/trace.go:171","msg":"trace[836941529] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:751; }","duration":"207.156096ms","start":"2026-02-05T17:44:24.3957Z","end":"2026-02-05T17:44:24.602856Z","steps":["trace[836941529] 'agreement among raft nodes before linearized reading' (duration: 207.072314ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:36.704117Z","caller":"traceutil/trace.go:171","msg":"trace[1354264919] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"249.505334ms","start":"2026-02-05T17:44:36.454574Z","end":"2026-02-05T17:44:36.70408Z","steps":["trace[1354264919] 'read index received' (duration: 249.489134ms)","trace[1354264919] 'applied index is now lower than readState.Index' (duration: 15.35µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:44:36.704239Z","caller":"traceutil/trace.go:171","msg":"trace[591276924] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"251.192133ms","start":"2026-02-05T17:44:36.453027Z","end":"2026-02-05T17:44:36.70422Z","steps":["trace[591276924] 'process raft request' (duration: 250.911367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:36.704354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.739589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:44:36.704386Z","caller":"traceutil/trace.go:171","msg":"trace[1931854798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"249.815361ms","start":"2026-02-05T17:44:36.454559Z","end":"2026-02-05T17:44:36.704374Z","steps":["trace[1931854798] 'agreement among raft nodes before linearized reading' (duration: 249.659057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:37.165935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.90287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:44:37.166048Z","caller":"traceutil/trace.go:171","msg":"trace[1295286872] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"710.951644ms","start":"2026-02-05T17:44:36.455083Z","end":"2026-02-05T17:44:37.166035Z","steps":["trace[1295286872] 'process raft request' (duration: 390.87556ms)","trace[1295286872] 'compare' (duration: 319.510191ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:37.166093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:36.455076Z","time spent":"710.995015ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:44:37.166128Z","caller":"traceutil/trace.go:171","msg":"trace[746079679] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"457.961159ms","start":"2026-02-05T17:44:36.708152Z","end":"2026-02-05T17:44:37.166114Z","steps":["trace[746079679] 'process raft request' (duration: 457.916618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:37.166195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:36.708136Z","time spent":"458.02474ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:44:37.166328Z","caller":"traceutil/trace.go:171","msg":"trace[1818134627] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"547.744083ms","start":"2026-02-05T17:44:36.618574Z","end":"2026-02-05T17:44:37.166318Z","steps":["trace[1818134627] 'process raft request' (duration: 547.430506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:37.166382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:36.618556Z","time spent":"547.796275ms","remote":"127.0.0.1:47268","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-05T17:44:37.166464Z","caller":"traceutil/trace.go:171","msg":"trace[1267471326] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"462.259319ms","start":"2026-02-05T17:44:36.704193Z","end":"2026-02-05T17:44:37.166453Z","steps":["trace[1267471326] 'read index received' (duration: 141.768285ms)","trace[1267471326] 'applied index is now lower than readState.Index' (duration: 320.490034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:37.166513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.48762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T17:44:37.166658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.765271ms","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-05T17:44:37.166691Z","caller":"traceutil/trace.go:171","msg":"trace[1666122844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"475.812862ms","start":"2026-02-05T17:44:36.690866Z","end":"2026-02-05T17:44:37.166679Z","steps":["trace[1666122844] 'agreement among raft nodes before linearized reading' (duration: 475.70963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:37.166719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:36.69086Z","time spent":"475.851523ms","remote":"127.0.0.1:47268","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-05T17:44:37.166982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.850232ms","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-05T17:44:37.167011Z","caller":"traceutil/trace.go:171","msg":"trace[241991755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"481.906593ms","start":"2026-02-05T17:44:36.685095Z","end":"2026-02-05T17:44:37.167002Z","steps":["trace[241991755] 'agreement among raft nodes before linearized reading' (duration: 481.830032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:37.167035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:36.68508Z","time spent":"481.948624ms","remote":"127.0.0.1:47268","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-05T17:44:37.171722Z","caller":"traceutil/trace.go:171","msg":"trace[491989249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"606.548242ms","start":"2026-02-05T17:44:36.559983Z","end":"2026-02-05T17:44:37.166531Z","steps":["trace[491989249] 'agreement among raft nodes before linearized reading' (duration: 606.505291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:37.171819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:36.559947Z","time spent":"611.822964ms","remote":"127.0.0.1:47028","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-05T17:44:37.311628Z","caller":"traceutil/trace.go:171","msg":"trace[1139600667] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"128.291404ms","start":"2026-02-05T17:44:37.183295Z","end":"2026-02-05T17:44:37.311587Z","steps":["trace[1139600667] 'process raft request' (duration: 128.258973ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:37.312105Z","caller":"traceutil/trace.go:171","msg":"trace[778126312] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"134.966638ms","start":"2026-02-05T17:44:37.177129Z","end":"2026-02-05T17:44:37.312095Z","steps":["trace[778126312] 'process raft request' (duration: 102.113528ms)","trace[778126312] 'compare' (duration: 32.220155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:42.436353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.125703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:44:42.437423Z","caller":"traceutil/trace.go:171","msg":"trace[143731284] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"1.003554254s","start":"2026-02-05T17:44:41.433848Z","end":"2026-02-05T17:44:42.437402Z","steps":["trace[143731284] 'read index received' (duration: 167.372047ms)","trace[143731284] 'applied index is now lower than readState.Index' (duration: 836.180927ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:44:42.437632Z","caller":"traceutil/trace.go:171","msg":"trace[1141575245] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.108450917s","start":"2026-02-05T17:44:41.329171Z","end":"2026-02-05T17:44:42.437622Z","steps":["trace[1141575245] 'process raft request' (duration: 272.001343ms)","trace[1141575245] 'compare' (duration: 834.845177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:42.437729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:41.329154Z","time spent":"1.10854716s","remote":"127.0.0.1:47268","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-05T17:44:42.43797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004113368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:42.43804Z","caller":"traceutil/trace.go:171","msg":"trace[1838232629] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:800; }","duration":"1.00420954s","start":"2026-02-05T17:44:41.433802Z","end":"2026-02-05T17:44:42.438011Z","steps":["trace[1838232629] 'agreement among raft nodes before linearized reading' (duration: 1.004079447s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:42.438253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:41.43378Z","time spent":"1.004445835s","remote":"127.0.0.1:47156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:44:42.438482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.326481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:44:42.438661Z","caller":"traceutil/trace.go:171","msg":"trace[1386893563] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:800; }","duration":"517.630998ms","start":"2026-02-05T17:44:41.921015Z","end":"2026-02-05T17:44:42.438646Z","steps":["trace[1386893563] 'agreement among raft nodes before linearized reading' (duration: 517.430174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:42.438804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:41.920997Z","time spent":"517.796181ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:44:42.437836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.936155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:44:42.439646Z","caller":"traceutil/trace.go:171","msg":"trace[1999486980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"864.814848ms","start":"2026-02-05T17:44:41.574816Z","end":"2026-02-05T17:44:42.439631Z","steps":["trace[1999486980] 'agreement among raft nodes before linearized reading' (duration: 862.934365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:42.439747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:41.5748Z","time spent":"864.929102ms","remote":"127.0.0.1:47028","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-05T17:44:58.588721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.161858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T17:44:58.588838Z","caller":"traceutil/trace.go:171","msg":"trace[2065143841] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"174.987502ms","start":"2026-02-05T17:44:58.413823Z","end":"2026-02-05T17:44:58.58881Z","steps":["trace[2065143841] 'read index received' (duration: 57.101418ms)","trace[2065143841] 'applied index is now lower than readState.Index' (duration: 117.885194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:58.588962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.139035ms","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-05T17:44:58.588976Z","caller":"traceutil/trace.go:171","msg":"trace[544202772] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:905; }","duration":"175.159686ms","start":"2026-02-05T17:44:58.413811Z","end":"2026-02-05T17:44:58.588971Z","steps":["trace[544202772] 'agreement among raft nodes before linearized reading' (duration: 175.070204ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:59.094533Z","caller":"traceutil/trace.go:171","msg":"trace[924923089] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"218.958748ms","start":"2026-02-05T17:44:58.875558Z","end":"2026-02-05T17:44:59.094516Z","steps":["trace[924923089] 'read index received' (duration: 218.780994ms)","trace[924923089] 'applied index is now lower than readState.Index' (duration: 177.154µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:59.094693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.117831ms","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-05T17:44:59.094758Z","caller":"traceutil/trace.go:171","msg":"trace[381528785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:906; }","duration":"219.220544ms","start":"2026-02-05T17:44:58.87553Z","end":"2026-02-05T17:44:59.094751Z","steps":["trace[381528785] 'agreement among raft nodes before linearized reading' (duration: 219.055771ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:44:59.094984Z","caller":"traceutil/trace.go:171","msg":"trace[914189506] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"472.948115ms","start":"2026-02-05T17:44:58.622029Z","end":"2026-02-05T17:44:59.094978Z","steps":["trace[914189506] 'process raft request' (duration: 472.386683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:59.09506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:58.622015Z","time spent":"472.990737ms","remote":"127.0.0.1:47268","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-05T17:45:01.657393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610910177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:45:02.15804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610910177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:45:02.658927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610910177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:45:03.139637Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.033818883s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:45:03.139888Z","caller":"traceutil/trace.go:171","msg":"trace[1067943998] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"1.983180924s","start":"2026-02-05T17:45:01.156693Z","end":"2026-02-05T17:45:03.139874Z","steps":["trace[1067943998] 'read index received' (duration: 1.98301062s)","trace[1067943998] 'applied index is now lower than readState.Index' (duration: 169.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:03.140098Z","caller":"traceutil/trace.go:171","msg":"trace[1215389868] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"2.034328644s","start":"2026-02-05T17:45:01.105763Z","end":"2026-02-05T17:45:03.140092Z","steps":["trace[1215389868] 'process raft request' (duration: 2.033977396s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:01.105754Z","time spent":"2.034362345s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:45:03.140295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983601133s","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-05T17:45:03.140314Z","caller":"traceutil/trace.go:171","msg":"trace[1496059178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"1.983639823s","start":"2026-02-05T17:45:01.156669Z","end":"2026-02-05T17:45:03.140309Z","steps":["trace[1496059178] 'agreement among raft nodes before linearized reading' (duration: 1.983577942s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:01.156658Z","time spent":"1.983666935s","remote":"127.0.0.1:47268","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-05T17:45:03.140411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786082811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:45:03.140423Z","caller":"traceutil/trace.go:171","msg":"trace[1595348317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.786117691s","start":"2026-02-05T17:45:01.354302Z","end":"2026-02-05T17:45:03.140419Z","steps":["trace[1595348317] 'agreement among raft nodes before linearized reading' (duration: 1.7860819s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:01.354288Z","time spent":"1.786143381s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:45:03.140542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.849652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:45:03.140555Z","caller":"traceutil/trace.go:171","msg":"trace[1944186534] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:911; }","duration":"120.887493ms","start":"2026-02-05T17:45:03.019663Z","end":"2026-02-05T17:45:03.140551Z","steps":["trace[1944186534] 'agreement among raft nodes before linearized reading' (duration: 120.850542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.899171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:03.140632Z","caller":"traceutil/trace.go:171","msg":"trace[2114377293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"484.933112ms","start":"2026-02-05T17:45:02.655696Z","end":"2026-02-05T17:45:03.140629Z","steps":["trace[2114377293] 'agreement among raft nodes before linearized reading' (duration: 484.915031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:02.655683Z","time spent":"484.957033ms","remote":"127.0.0.1:47028","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-05T17:45:03.140716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675630559s","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-05T17:45:03.140728Z","caller":"traceutil/trace.go:171","msg":"trace[536476717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"1.675666229s","start":"2026-02-05T17:45:01.465058Z","end":"2026-02-05T17:45:03.140724Z","steps":["trace[536476717] 'agreement among raft nodes before linearized reading' (duration: 1.675633328s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:01.465044Z","time spent":"1.675692119s","remote":"127.0.0.1:47268","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-05T17:45:03.140812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775563267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:45:03.140823Z","caller":"traceutil/trace.go:171","msg":"trace[608305183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.775589017s","start":"2026-02-05T17:45:01.365231Z","end":"2026-02-05T17:45:03.14082Z","steps":["trace[608305183] 'agreement among raft nodes before linearized reading' (duration: 1.775556196s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.140834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:01.365223Z","time spent":"1.775607867s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T17:45:03.425867Z","caller":"traceutil/trace.go:171","msg":"trace[67783588] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"268.550604ms","start":"2026-02-05T17:45:03.1573Z","end":"2026-02-05T17:45:03.425851Z","steps":["trace[67783588] 'process raft request' (duration: 268.522473ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:03.426644Z","caller":"traceutil/trace.go:171","msg":"trace[171102310] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"277.737646ms","start":"2026-02-05T17:45:03.148896Z","end":"2026-02-05T17:45:03.426633Z","steps":["trace[171102310] 'process raft request' (duration: 218.61257ms)","trace[171102310] 'compare' (duration: 58.161504ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:03.426751Z","caller":"traceutil/trace.go:171","msg":"trace[1549661339] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"276.992989ms","start":"2026-02-05T17:45:03.149751Z","end":"2026-02-05T17:45:03.426744Z","steps":["trace[1549661339] 'process raft request' (duration: 276.007427ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:03.42687Z","caller":"traceutil/trace.go:171","msg":"trace[1048540172] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"276.089407ms","start":"2026-02-05T17:45:03.150771Z","end":"2026-02-05T17:45:03.42686Z","steps":["trace[1048540172] 'process raft request' (duration: 275.012483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:03.898517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.649413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:45:03.898639Z","caller":"traceutil/trace.go:171","msg":"trace[2011474687] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"684.036563ms","start":"2026-02-05T17:45:03.214583Z","end":"2026-02-05T17:45:03.89862Z","steps":["trace[2011474687] 'process raft request' (duration: 568.180416ms)","trace[2011474687] 'compare' (duration: 115.238092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:45:03.898692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:03.214572Z","time spent":"684.097434ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:45:07.658548Z","caller":"traceutil/trace.go:171","msg":"trace[95070382] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"220.858424ms","start":"2026-02-05T17:45:07.437675Z","end":"2026-02-05T17:45:07.658534Z","steps":["trace[95070382] 'read index received' (duration: 220.761581ms)","trace[95070382] 'applied index is now lower than readState.Index' (duration: 96.423µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:07.658611Z","caller":"traceutil/trace.go:171","msg":"trace[116735966] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"426.279696ms","start":"2026-02-05T17:45:07.232327Z","end":"2026-02-05T17:45:07.658606Z","steps":["trace[116735966] 'process raft request' (duration: 426.118052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:07.658668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:07.232317Z","time spent":"426.307758ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:45:07.658758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.621658ms","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-05T17:45:07.65878Z","caller":"traceutil/trace.go:171","msg":"trace[1006765121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"220.661868ms","start":"2026-02-05T17:45:07.438112Z","end":"2026-02-05T17:45:07.658774Z","steps":["trace[1006765121] 'agreement among raft nodes before linearized reading' (duration: 220.581096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:07.65885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.35363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:45:07.658865Z","caller":"traceutil/trace.go:171","msg":"trace[1010983260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"217.3772ms","start":"2026-02-05T17:45:07.441483Z","end":"2026-02-05T17:45:07.65886Z","steps":["trace[1010983260] 'agreement among raft nodes before linearized reading' (duration: 217.333399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:07.658868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.483329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:45:07.658882Z","caller":"traceutil/trace.go:171","msg":"trace[1481061877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"219.506681ms","start":"2026-02-05T17:45:07.439371Z","end":"2026-02-05T17:45:07.658878Z","steps":["trace[1481061877] 'agreement among raft nodes before linearized reading' (duration: 219.46959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:07.658945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.270503ms","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-05T17:45:07.658957Z","caller":"traceutil/trace.go:171","msg":"trace[1118069821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"221.304484ms","start":"2026-02-05T17:45:07.437649Z","end":"2026-02-05T17:45:07.658954Z","steps":["trace[1118069821] 'agreement among raft nodes before linearized reading' (duration: 221.268553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:13.487734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.026276ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:13.48785Z","caller":"traceutil/trace.go:171","msg":"trace[943228381] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:938; }","duration":"116.151949ms","start":"2026-02-05T17:45:13.371688Z","end":"2026-02-05T17:45:13.48784Z","steps":["trace[943228381] 'range keys from in-memory index tree' (duration: 116.014585ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:13.48799Z","caller":"traceutil/trace.go:171","msg":"trace[1008198980] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"390.939962ms","start":"2026-02-05T17:45:13.097043Z","end":"2026-02-05T17:45:13.487983Z","steps":["trace[1008198980] 'process raft request' (duration: 293.525012ms)","trace[1008198980] 'compare' (duration: 96.895548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:45:13.488049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:13.097032Z","time spent":"390.973552ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:45:13.89152Z","caller":"traceutil/trace.go:171","msg":"trace[947151186] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"164.162588ms","start":"2026-02-05T17:45:13.727337Z","end":"2026-02-05T17:45:13.891499Z","steps":["trace[947151186] 'process raft request' (duration: 164.054675ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:13.92255Z","caller":"traceutil/trace.go:171","msg":"trace[887909774] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"190.148679ms","start":"2026-02-05T17:45:13.732383Z","end":"2026-02-05T17:45:13.922531Z","steps":["trace[887909774] 'process raft request' (duration: 190.122498ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:13.922723Z","caller":"traceutil/trace.go:171","msg":"trace[718875717] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"194.546334ms","start":"2026-02-05T17:45:13.728156Z","end":"2026-02-05T17:45:13.922703Z","steps":["trace[718875717] 'process raft request' (duration: 194.205676ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:13.922836Z","caller":"traceutil/trace.go:171","msg":"trace[944526035] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"192.086685ms","start":"2026-02-05T17:45:13.73074Z","end":"2026-02-05T17:45:13.922826Z","steps":["trace[944526035] 'process raft request' (duration: 191.735647ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:15.738305Z","caller":"traceutil/trace.go:171","msg":"trace[388086741] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"176.187914ms","start":"2026-02-05T17:45:15.562094Z","end":"2026-02-05T17:45:15.738282Z","steps":["trace[388086741] 'read index received' (duration: 176.0183ms)","trace[388086741] 'applied index is now lower than readState.Index' (duration: 168.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:15.738643Z","caller":"traceutil/trace.go:171","msg":"trace[852978329] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"428.802808ms","start":"2026-02-05T17:45:15.309828Z","end":"2026-02-05T17:45:15.738631Z","steps":["trace[852978329] 'process raft request' (duration: 428.344207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:15.738735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:15.309802Z","time spent":"428.870639ms","remote":"127.0.0.1:47268","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-05T17:45:15.738918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.820289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:15.738949Z","caller":"traceutil/trace.go:171","msg":"trace[96031036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"176.872961ms","start":"2026-02-05T17:45:15.562067Z","end":"2026-02-05T17:45:15.73894Z","steps":["trace[96031036] 'agreement among raft nodes before linearized reading' (duration: 176.82595ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:15.948925Z","caller":"traceutil/trace.go:171","msg":"trace[1569877572] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"192.879363ms","start":"2026-02-05T17:45:15.756026Z","end":"2026-02-05T17:45:15.948905Z","steps":["trace[1569877572] 'process raft request' (duration: 96.625981ms)","trace[1569877572] 'compare' (duration: 96.077098ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:17.345712Z","caller":"traceutil/trace.go:171","msg":"trace[321413404] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"122.649205ms","start":"2026-02-05T17:45:17.223047Z","end":"2026-02-05T17:45:17.345697Z","steps":["trace[321413404] 'process raft request' (duration: 122.553733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:18.209465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.923345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:18.209518Z","caller":"traceutil/trace.go:171","msg":"trace[695136435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"651.985726ms","start":"2026-02-05T17:45:17.557519Z","end":"2026-02-05T17:45:18.209505Z","steps":["trace[695136435] 'range keys from in-memory index tree' (duration: 651.875644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:18.209544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.55748Z","time spent":"652.057828ms","remote":"127.0.0.1:47028","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-05T17:45:18.457244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610910279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:45:18.86611Z","caller":"traceutil/trace.go:171","msg":"trace[2024214537] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"994.128321ms","start":"2026-02-05T17:45:17.871951Z","end":"2026-02-05T17:45:18.866079Z","steps":["trace[2024214537] 'process raft request' (duration: 993.891185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:18.86627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.87193Z","time spent":"994.255354ms","remote":"127.0.0.1:47268","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-05T17:45:18.866661Z","caller":"traceutil/trace.go:171","msg":"trace[1704741748] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:994; }","duration":"910.330537ms","start":"2026-02-05T17:45:17.956322Z","end":"2026-02-05T17:45:18.866652Z","steps":["trace[1704741748] 'read index received' (duration: 910.325927ms)","trace[1704741748] 'applied index is now lower than readState.Index' (duration: 4.07µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:45:18.866804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.47895ms","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-05T17:45:18.866827Z","caller":"traceutil/trace.go:171","msg":"trace[795004271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"910.546252ms","start":"2026-02-05T17:45:17.956274Z","end":"2026-02-05T17:45:18.86682Z","steps":["trace[795004271] 'agreement among raft nodes before linearized reading' (duration: 910.431459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:18.866851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.95625Z","time spent":"910.593882ms","remote":"127.0.0.1:47268","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-05T17:45:19.366796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836958328610910282,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:45:20.220893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009979581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-05T17:45:20.221183Z","caller":"traceutil/trace.go:171","msg":"trace[654419101] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.010322239s","start":"2026-02-05T17:45:18.210845Z","end":"2026-02-05T17:45:20.221167Z","steps":["trace[654419101] 'agreement among raft nodes before linearized reading' (duration: 2.010016392s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.221429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:18.210823Z","time spent":"2.010552545s","remote":"127.0.0.1:47034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/05 17:45:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-05T17:45:20.589839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148025572s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T17:45:20.590485Z","caller":"traceutil/trace.go:171","msg":"trace[576196256] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"1.723776905s","start":"2026-02-05T17:45:18.866679Z","end":"2026-02-05T17:45:20.590456Z","steps":["trace[576196256] 'read index received' (duration: 575.069326ms)","trace[576196256] 'applied index is now lower than readState.Index' (duration: 1.148704999s)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:20.590614Z","caller":"traceutil/trace.go:171","msg":"trace[789106821] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.556522253s","start":"2026-02-05T17:45:19.034081Z","end":"2026-02-05T17:45:20.590603Z","steps":["trace[789106821] 'process raft request' (duration: 1.555895408s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.590706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:19.034048Z","time spent":"1.556585765s","remote":"127.0.0.1:47268","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-05T17:45:20.590926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768549545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:20.590956Z","caller":"traceutil/trace.go:171","msg":"trace[1391579819] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:953; }","duration":"1.768628617s","start":"2026-02-05T17:45:18.822318Z","end":"2026-02-05T17:45:20.590946Z","steps":["trace[1391579819] 'agreement among raft nodes before linearized reading' (duration: 1.768558745s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.590981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:18.82229Z","time spent":"1.768685129s","remote":"127.0.0.1:33632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:45:20.591181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.598703144s","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-05T17:45:20.5912Z","caller":"traceutil/trace.go:171","msg":"trace[631876002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"2.598759745s","start":"2026-02-05T17:45:17.992434Z","end":"2026-02-05T17:45:20.591194Z","steps":["trace[631876002] 'agreement among raft nodes before linearized reading' (duration: 2.598643942s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.591218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.992413Z","time spent":"2.598801345s","remote":"127.0.0.1:47268","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-05T17:45:20.591357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.623925977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:45:20.591375Z","caller":"traceutil/trace.go:171","msg":"trace[278661996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"2.623951867s","start":"2026-02-05T17:45:17.967417Z","end":"2026-02-05T17:45:20.591369Z","steps":["trace[278661996] 'agreement among raft nodes before linearized reading' (duration: 2.623900586s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.591391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.967408Z","time spent":"2.623979127s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:45:20.591636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61691836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:45:20.591723Z","caller":"traceutil/trace.go:171","msg":"trace[1301801397] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:953; }","duration":"1.617075453s","start":"2026-02-05T17:45:18.974627Z","end":"2026-02-05T17:45:20.591702Z","steps":["trace[1301801397] 'agreement among raft nodes before linearized reading' (duration: 1.616814596s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.591814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.625057945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:20.591843Z","caller":"traceutil/trace.go:171","msg":"trace[97094155] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:953; }","duration":"2.625126796s","start":"2026-02-05T17:45:17.966708Z","end":"2026-02-05T17:45:20.591835Z","steps":["trace[97094155] 'agreement among raft nodes before linearized reading' (duration: 2.625054324s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.591838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:18.974599Z","time spent":"1.617224357s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:45:20.591865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.966703Z","time spent":"2.625156117s","remote":"127.0.0.1:47494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:45:20.592004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.62487464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:45:20.592021Z","caller":"traceutil/trace.go:171","msg":"trace[43804121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"2.62490063s","start":"2026-02-05T17:45:17.967115Z","end":"2026-02-05T17:45:20.592016Z","steps":["trace[43804121] 'agreement among raft nodes before linearized reading' (duration: 2.624845059s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.592038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.967111Z","time spent":"2.624923671s","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:45:20.592152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.625360451s","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-05T17:45:20.592169Z","caller":"traceutil/trace.go:171","msg":"trace[1346689618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"2.625376792s","start":"2026-02-05T17:45:17.966787Z","end":"2026-02-05T17:45:20.592164Z","steps":["trace[1346689618] 'agreement among raft nodes before linearized reading' (duration: 2.625327131s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.59219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:17.966774Z","time spent":"2.625411532s","remote":"127.0.0.1:47268","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-05T17:45:20.592153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.334707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:45:20.592224Z","caller":"traceutil/trace.go:171","msg":"trace[622228992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"283.43069ms","start":"2026-02-05T17:45:20.308781Z","end":"2026-02-05T17:45:20.592212Z","steps":["trace[622228992] 'agreement among raft nodes before linearized reading' (duration: 283.332098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.592413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384519829s","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-05T17:45:20.592436Z","caller":"traceutil/trace.go:171","msg":"trace[692761502] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:953; }","duration":"1.38456831s","start":"2026-02-05T17:45:19.207861Z","end":"2026-02-05T17:45:20.592429Z","steps":["trace[692761502] 'agreement among raft nodes before linearized reading' (duration: 1.384531369s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:20.592457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:19.207839Z","time spent":"1.384613071s","remote":"127.0.0.1:47274","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-05T17:45:21.449858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.020178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:45:21.450341Z","caller":"traceutil/trace.go:171","msg":"trace[174036472] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"833.844907ms","start":"2026-02-05T17:45:20.616477Z","end":"2026-02-05T17:45:21.450322Z","steps":["trace[174036472] 'process raft request' (duration: 209.249486ms)","trace[174036472] 'compare' (duration: 623.617238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:45:21.450389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:20.616447Z","time spent":"833.922889ms","remote":"127.0.0.1:47268","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-05T17:45:21.450529Z","caller":"traceutil/trace.go:171","msg":"trace[175692668] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"830.952117ms","start":"2026-02-05T17:45:20.619572Z","end":"2026-02-05T17:45:21.450524Z","steps":["trace[175692668] 'process raft request' (duration: 830.389843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:21.450558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:20.619561Z","time spent":"830.978308ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:45:21.450621Z","caller":"traceutil/trace.go:171","msg":"trace[952095587] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"830.987078ms","start":"2026-02-05T17:45:20.619629Z","end":"2026-02-05T17:45:21.450617Z","steps":["trace[952095587] 'process raft request' (duration: 830.425425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:21.450644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:20.619625Z","time spent":"831.005748ms","remote":"127.0.0.1:47268","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-05T17:45:21.450708Z","caller":"traceutil/trace.go:171","msg":"trace[1484239916] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"829.157725ms","start":"2026-02-05T17:45:20.621546Z","end":"2026-02-05T17:45:21.450703Z","steps":["trace[1484239916] 'process raft request' (duration: 828.53413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:21.451052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:45:20.621532Z","time spent":"829.186735ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:45:23.268053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.914525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:45:23.268159Z","caller":"traceutil/trace.go:171","msg":"trace[741099364] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:969; }","duration":"244.103549ms","start":"2026-02-05T17:45:23.024035Z","end":"2026-02-05T17:45:23.268138Z","steps":["trace[741099364] 'range keys from in-memory index tree' (duration: 243.70778ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:45.825554Z","caller":"traceutil/trace.go:171","msg":"trace[454704068] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"170.965369ms","start":"2026-02-05T17:45:45.654564Z","end":"2026-02-05T17:45:45.82553Z","steps":["trace[454704068] 'read index received' (duration: 12.521569ms)","trace[454704068] 'applied index is now lower than readState.Index' (duration: 158.44261ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:45.83338Z","caller":"traceutil/trace.go:171","msg":"trace[220223936] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"177.990847ms","start":"2026-02-05T17:45:45.655377Z","end":"2026-02-05T17:45:45.833368Z","steps":["trace[220223936] 'process raft request' (duration: 170.099659ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:45.825778Z","caller":"traceutil/trace.go:171","msg":"trace[1512345271] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"172.935085ms","start":"2026-02-05T17:45:45.652823Z","end":"2026-02-05T17:45:45.825758Z","steps":["trace[1512345271] 'process raft request' (duration: 172.475914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:45.83373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.55608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-05T17:45:45.833852Z","caller":"traceutil/trace.go:171","msg":"trace[1416298793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"178.704155ms","start":"2026-02-05T17:45:45.655139Z","end":"2026-02-05T17:45:45.833843Z","steps":["trace[1416298793] 'agreement among raft nodes before linearized reading' (duration: 178.48696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:45:45.833946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.36357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T17:45:45.834009Z","caller":"traceutil/trace.go:171","msg":"trace[2003582308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"179.446082ms","start":"2026-02-05T17:45:45.654543Z","end":"2026-02-05T17:45:45.833989Z","steps":["trace[2003582308] 'agreement among raft nodes before linearized reading' (duration: 179.156675ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:48.195037Z","caller":"traceutil/trace.go:171","msg":"trace[1435282512] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"240.82774ms","start":"2026-02-05T17:45:47.954179Z","end":"2026-02-05T17:45:48.195006Z","steps":["trace[1435282512] 'process raft request' (duration: 239.96029ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:48.195553Z","caller":"traceutil/trace.go:171","msg":"trace[1617922281] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1164; }","duration":"200.70296ms","start":"2026-02-05T17:45:47.994842Z","end":"2026-02-05T17:45:48.195545Z","steps":["trace[1617922281] 'read index received' (duration: 200.69913ms)","trace[1617922281] 'applied index is now lower than readState.Index' (duration: 3.18µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:45:48.195661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.800872ms","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:529"} {"level":"info","ts":"2026-02-05T17:45:48.195681Z","caller":"traceutil/trace.go:171","msg":"trace[1747487002] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1115; }","duration":"200.859654ms","start":"2026-02-05T17:45:47.994815Z","end":"2026-02-05T17:45:48.195675Z","steps":["trace[1747487002] 'agreement among raft nodes before linearized reading' (duration: 200.757382ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:50.941363Z","caller":"traceutil/trace.go:171","msg":"trace[1293852731] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"143.844961ms","start":"2026-02-05T17:45:50.797472Z","end":"2026-02-05T17:45:50.941317Z","steps":["trace[1293852731] 'process raft request' (duration: 143.618096ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:52.121049Z","caller":"traceutil/trace.go:171","msg":"trace[7873261] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"124.60006ms","start":"2026-02-05T17:45:51.996413Z","end":"2026-02-05T17:45:52.121013Z","steps":["trace[7873261] 'process raft request' (duration: 68.123949ms)","trace[7873261] 'compare' (duration: 56.320147ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:54.304188Z","caller":"traceutil/trace.go:171","msg":"trace[1809883421] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"102.143734ms","start":"2026-02-05T17:45:54.202005Z","end":"2026-02-05T17:45:54.304149Z","steps":["trace[1809883421] 'process raft request' (duration: 101.954059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:46:41.5265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.343628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:46:41.526645Z","caller":"traceutil/trace.go:171","msg":"trace[1819867717] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"197.134872ms","start":"2026-02-05T17:46:41.329487Z","end":"2026-02-05T17:46:41.526622Z","steps":["trace[1819867717] 'process raft request' (duration: 83.527368ms)","trace[1819867717] 'compare' (duration: 112.977689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:48:10.833841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.596129ms","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-05T17:48:10.834665Z","caller":"traceutil/trace.go:171","msg":"trace[431207388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1765; }","duration":"102.455498ms","start":"2026-02-05T17:48:10.732198Z","end":"2026-02-05T17:48:10.834653Z","steps":["trace[431207388] 'range keys from in-memory index tree' (duration: 101.482437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:48:10.835113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.836772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:360325"} {"level":"info","ts":"2026-02-05T17:48:10.835222Z","caller":"traceutil/trace.go:171","msg":"trace[487024440] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1765; }","duration":"130.970054ms","start":"2026-02-05T17:48:10.704243Z","end":"2026-02-05T17:48:10.835213Z","steps":["trace[487024440] 'range keys from in-memory index tree' (duration: 128.865637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:49:00.887465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.352772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:49:00.887516Z","caller":"traceutil/trace.go:171","msg":"trace[1166833382] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2020; }","duration":"531.436213ms","start":"2026-02-05T17:49:00.356069Z","end":"2026-02-05T17:49:00.887506Z","steps":["trace[1166833382] 'range keys from in-memory index tree' (duration: 531.24644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:49:00.887538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:49:00.356055Z","time spent":"531.479015ms","remote":"127.0.0.1:47268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T17:49:00.887658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.864378ms","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-05T17:49:00.887672Z","caller":"traceutil/trace.go:171","msg":"trace[1760046077] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2020; }","duration":"454.914099ms","start":"2026-02-05T17:49:00.432753Z","end":"2026-02-05T17:49:00.887667Z","steps":["trace[1760046077] 'range keys from in-memory index tree' (duration: 454.774536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:49:00.887698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:49:00.432732Z","time spent":"454.96272ms","remote":"127.0.0.1:47268","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":"warn","ts":"2026-02-05T17:49:00.887758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.991447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:49:00.887771Z","caller":"traceutil/trace.go:171","msg":"trace[1552999647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2020; }","duration":"328.043128ms","start":"2026-02-05T17:49:00.559724Z","end":"2026-02-05T17:49:00.887767Z","steps":["trace[1552999647] 'range keys from in-memory index tree' (duration: 327.896095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:49:00.887794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:49:00.559705Z","time spent":"328.074548ms","remote":"127.0.0.1:47028","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-05T17:49:00.88788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.264763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-05T17:49:00.887894Z","caller":"traceutil/trace.go:171","msg":"trace[1171378846] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2020; }","duration":"295.307444ms","start":"2026-02-05T17:49:00.592583Z","end":"2026-02-05T17:49:00.88789Z","steps":["trace[1171378846] 'range keys from in-memory index tree' (duration: 295.182011ms)"],"step_count":1}