{"level":"warn","ts":"2026-02-11T01:51:10.453102Z","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-11T01:51:10.453297Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-11T01:51:10.45342Z","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-11T01:51:10.453482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-11T01:51:10.453546Z","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-11T01:51:10.456241Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-02-11T01:51:10.456362Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-11T01:51:10.46224Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.656751ms"} {"level":"info","ts":"2026-02-11T01:51:10.485692Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-02-11T01:51:10.485813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:51:10.485843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-02-11T01:51:10.485879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:51:10.485923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-02-11T01:51:10.485977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-02-11T01:51:10.500268Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:51:10.504803Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:51:10.508764Z","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-11T01:51:10.511188Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:51:10.520019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:51:10.52011Z","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-11T01:51:10.520145Z","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-11T01:51:10.520151Z","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-11T01:51:10.520674Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-11T01:51:10.520685Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-02-11T01:51:10.520582Z","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-11T01:51:10.521602Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:51:10.521622Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:51:10.523769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-02-11T01:51:10.523871Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-02-11T01:51:11.392125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:51:11.392166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:51:11.392185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-02-11T01:51:11.392194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:51:11.392203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-11T01:51:11.392211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-02-11T01:51:11.392218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-02-11T01:51:11.393186Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:51:11.393205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:51:11.393447Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:11.393572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:51:11.393669Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:51:11.393677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:51:11.394842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-02-11T01:51:11.402759Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:51:11.428079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:11.431542Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:11.431675Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:14.068974Z","caller":"traceutil/trace.go:171","msg":"trace[859087895] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"100.481401ms","start":"2026-02-11T01:51:13.968469Z","end":"2026-02-11T01:51:14.06895Z","steps":["trace[859087895] 'process raft request' (duration: 37.476906ms)","trace[859087895] 'compare' (duration: 62.655957ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:15.44564Z","caller":"traceutil/trace.go:171","msg":"trace[1806160133] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"120.76612ms","start":"2026-02-11T01:51:15.324843Z","end":"2026-02-11T01:51:15.445609Z","steps":["trace[1806160133] 'process raft request' (duration: 97.815349ms)","trace[1806160133] 'compare' (duration: 22.823578ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:15.91799Z","caller":"traceutil/trace.go:171","msg":"trace[407031447] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"253.525907ms","start":"2026-02-11T01:51:15.66443Z","end":"2026-02-11T01:51:15.917956Z","steps":["trace[407031447] 'process raft request' (duration: 253.361503ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:19.558197Z","caller":"traceutil/trace.go:171","msg":"trace[258794663] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"110.748928ms","start":"2026-02-11T01:51:19.447431Z","end":"2026-02-11T01:51:19.55818Z","steps":["trace[258794663] 'process raft request' (duration: 94.133634ms)","trace[258794663] 'compare' (duration: 16.255096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:21.610807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449539745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:51:21.639863Z","caller":"traceutil/trace.go:171","msg":"trace[1338077036] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"578.714758ms","start":"2026-02-11T01:51:21.061123Z","end":"2026-02-11T01:51:21.639838Z","steps":["trace[1338077036] 'process raft request' (duration: 578.586845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.640346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.061099Z","time spent":"578.829281ms","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:51:21.640728Z","caller":"traceutil/trace.go:171","msg":"trace[1698369029] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:295; }","duration":"530.091595ms","start":"2026-02-11T01:51:21.110625Z","end":"2026-02-11T01:51:21.640717Z","steps":["trace[1698369029] 'read index received' (duration: 529.0105ms)","trace[1698369029] 'applied index is now lower than readState.Index' (duration: 1.080095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:21.640883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.247859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:21.640925Z","caller":"traceutil/trace.go:171","msg":"trace[1027004869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:286; }","duration":"530.319041ms","start":"2026-02-11T01:51:21.110594Z","end":"2026-02-11T01:51:21.640913Z","steps":["trace[1027004869] 'agreement among raft nodes before linearized reading' (duration: 530.207378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.640969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.110578Z","time spent":"530.384642ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:21.64112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.813341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:51:21.641147Z","caller":"traceutil/trace.go:171","msg":"trace[2123698877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:286; }","duration":"471.865291ms","start":"2026-02-11T01:51:21.169273Z","end":"2026-02-11T01:51:21.641138Z","steps":["trace[2123698877] 'agreement among raft nodes before linearized reading' (duration: 471.81284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.641172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.169255Z","time spent":"471.910723ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:21.641321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.84794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-11T01:51:21.641348Z","caller":"traceutil/trace.go:171","msg":"trace[1588692193] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:286; }","duration":"184.920612ms","start":"2026-02-11T01:51:21.456419Z","end":"2026-02-11T01:51:21.64134Z","steps":["trace[1588692193] 'agreement among raft nodes before linearized reading' (duration: 184.875761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.641511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.627262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:21.641536Z","caller":"traceutil/trace.go:171","msg":"trace[2016073431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:286; }","duration":"460.674882ms","start":"2026-02-11T01:51:21.180854Z","end":"2026-02-11T01:51:21.641529Z","steps":["trace[2016073431] 'agreement among raft nodes before linearized reading' (duration: 460.637011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.641561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.180841Z","time spent":"460.713384ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:51:21.641668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.339082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-11T01:51:21.641692Z","caller":"traceutil/trace.go:171","msg":"trace[1704097737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:286; }","duration":"472.370503ms","start":"2026-02-11T01:51:21.169314Z","end":"2026-02-11T01:51:21.641685Z","steps":["trace[1704097737] 'agreement among raft nodes before linearized reading' (duration: 472.332122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.641714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.169311Z","time spent":"472.398123ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:22.298049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.891902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:22.298138Z","caller":"traceutil/trace.go:171","msg":"trace[1782836797] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"647.043907ms","start":"2026-02-11T01:51:21.651078Z","end":"2026-02-11T01:51:22.298122Z","steps":["trace[1782836797] 'read index received' (duration: 435.869039ms)","trace[1782836797] 'applied index is now lower than readState.Index' (duration: 211.173418ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:22.298271Z","caller":"traceutil/trace.go:171","msg":"trace[1059630089] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"648.690995ms","start":"2026-02-11T01:51:21.649568Z","end":"2026-02-11T01:51:22.298259Z","steps":["trace[1059630089] 'process raft request' (duration: 437.370164ms)","trace[1059630089] 'compare' (duration: 210.227796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:22.29832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.649551Z","time spent":"648.746226ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:51:22.298515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.431406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-11T01:51:22.298544Z","caller":"traceutil/trace.go:171","msg":"trace[2116522351] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:287; }","duration":"647.494687ms","start":"2026-02-11T01:51:21.651041Z","end":"2026-02-11T01:51:22.298536Z","steps":["trace[2116522351] 'agreement among raft nodes before linearized reading' (duration: 647.407005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:22.298572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.651012Z","time spent":"647.553919ms","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"info","ts":"2026-02-11T01:51:22.463472Z","caller":"traceutil/trace.go:171","msg":"trace[325690221] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:296; }","duration":"165.290438ms","start":"2026-02-11T01:51:22.298163Z","end":"2026-02-11T01:51:22.463454Z","steps":["trace[325690221] 'read index received' (duration: 164.132611ms)","trace[325690221] 'applied index is now lower than readState.Index' (duration: 1.156927ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:22.463913Z","caller":"traceutil/trace.go:171","msg":"trace[1605123938] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"812.51813ms","start":"2026-02-11T01:51:21.651364Z","end":"2026-02-11T01:51:22.463883Z","steps":["trace[1605123938] 'process raft request' (duration: 810.976344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:22.464256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.651354Z","time spent":"812.624352ms","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:22.464567Z","caller":"traceutil/trace.go:171","msg":"trace[1932665371] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"813.142614ms","start":"2026-02-11T01:51:21.651412Z","end":"2026-02-11T01:51:22.464555Z","steps":["trace[1932665371] 'process raft request' (duration: 811.915996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:22.464621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.651409Z","time spent":"813.181965ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:22.464742Z","caller":"traceutil/trace.go:171","msg":"trace[1880327711] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"812.036048ms","start":"2026-02-11T01:51:21.652698Z","end":"2026-02-11T01:51:22.464734Z","steps":["trace[1880327711] 'process raft request' (duration: 810.724788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:22.464789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.652691Z","time spent":"812.070949ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:22.464913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.131771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:22.464973Z","caller":"traceutil/trace.go:171","msg":"trace[404772788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:290; }","duration":"480.188352ms","start":"2026-02-11T01:51:21.984745Z","end":"2026-02-11T01:51:22.464933Z","steps":["trace[404772788] 'agreement among raft nodes before linearized reading' (duration: 480.141751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:22.465005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.984725Z","time spent":"480.271925ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:22.468159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.251484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T01:51:22.468186Z","caller":"traceutil/trace.go:171","msg":"trace[13260447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:291; }","duration":"164.310805ms","start":"2026-02-11T01:51:22.303868Z","end":"2026-02-11T01:51:22.468178Z","steps":["trace[13260447] 'agreement among raft nodes before linearized reading' (duration: 164.239913ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:22.468285Z","caller":"traceutil/trace.go:171","msg":"trace[1444885600] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"164.377157ms","start":"2026-02-11T01:51:22.303902Z","end":"2026-02-11T01:51:22.468279Z","steps":["trace[1444885600] 'process raft request' (duration: 164.132272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:22.468367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.431558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:22.468382Z","caller":"traceutil/trace.go:171","msg":"trace[126615047] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:291; }","duration":"164.448009ms","start":"2026-02-11T01:51:22.30393Z","end":"2026-02-11T01:51:22.468378Z","steps":["trace[126615047] 'agreement among raft nodes before linearized reading' (duration: 164.421248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:23.207926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.380293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-11T01:51:23.208024Z","caller":"traceutil/trace.go:171","msg":"trace[654675936] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:306; }","duration":"198.509806ms","start":"2026-02-11T01:51:23.009498Z","end":"2026-02-11T01:51:23.208007Z","steps":["trace[654675936] 'range keys from in-memory index tree' (duration: 198.302851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:23.208364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.521797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:23.209189Z","caller":"traceutil/trace.go:171","msg":"trace[644930447] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"149.620277ms","start":"2026-02-11T01:51:23.059544Z","end":"2026-02-11T01:51:23.209165Z","steps":["trace[644930447] 'read index received' (duration: 27.174108ms)","trace[644930447] 'applied index is now lower than readState.Index' (duration: 122.444349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:23.209302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.726849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-11T01:51:23.209347Z","caller":"traceutil/trace.go:171","msg":"trace[982354999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:307; }","duration":"149.861312ms","start":"2026-02-11T01:51:23.059472Z","end":"2026-02-11T01:51:23.209333Z","steps":["trace[982354999] 'agreement among raft nodes before linearized reading' (duration: 149.759429ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:23.209189Z","caller":"traceutil/trace.go:171","msg":"trace[630456140] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"175.210688ms","start":"2026-02-11T01:51:23.033961Z","end":"2026-02-11T01:51:23.209172Z","steps":["trace[630456140] 'process raft request' (duration: 52.80083ms)","trace[630456140] 'compare' (duration: 121.147079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:23.209756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.350888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-11T01:51:23.209799Z","caller":"traceutil/trace.go:171","msg":"trace[216790676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:307; }","duration":"100.4217ms","start":"2026-02-11T01:51:23.109366Z","end":"2026-02-11T01:51:23.209788Z","steps":["trace[216790676] 'agreement among raft nodes before linearized reading' (duration: 100.328098ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:26.709042Z","caller":"traceutil/trace.go:171","msg":"trace[1396847777] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"154.54601ms","start":"2026-02-11T01:51:26.554479Z","end":"2026-02-11T01:51:26.709025Z","steps":["trace[1396847777] 'process raft request' (duration: 154.517119ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:26.710205Z","caller":"traceutil/trace.go:171","msg":"trace[1999801867] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"155.637025ms","start":"2026-02-11T01:51:26.554555Z","end":"2026-02-11T01:51:26.710193Z","steps":["trace[1999801867] 'process raft request' (duration: 83.629981ms)","trace[1999801867] 'compare' (duration: 70.721604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:27.007919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.201791ms","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-11T01:51:27.007987Z","caller":"traceutil/trace.go:171","msg":"trace[1076599322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"102.307143ms","start":"2026-02-11T01:51:26.905663Z","end":"2026-02-11T01:51:27.00797Z","steps":["trace[1076599322] 'range keys from in-memory index tree' (duration: 102.116498ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:27.31174Z","caller":"traceutil/trace.go:171","msg":"trace[1752033780] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"297.85611ms","start":"2026-02-11T01:51:27.013865Z","end":"2026-02-11T01:51:27.311721Z","steps":["trace[1752033780] 'process raft request' (duration: 297.754118ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.206506Z","caller":"traceutil/trace.go:171","msg":"trace[1509946952] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"209.162861ms","start":"2026-02-11T01:51:31.99732Z","end":"2026-02-11T01:51:32.206483Z","steps":["trace[1509946952] 'process raft request' (duration: 209.025938ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.4174Z","caller":"traceutil/trace.go:171","msg":"trace[1259886393] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"202.669652ms","start":"2026-02-11T01:51:32.214705Z","end":"2026-02-11T01:51:32.417375Z","steps":["trace[1259886393] 'process raft request' (duration: 142.610165ms)","trace[1259886393] 'compare' (duration: 59.957585ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:32.701414Z","caller":"traceutil/trace.go:171","msg":"trace[1749178867] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"206.304226ms","start":"2026-02-11T01:51:32.49509Z","end":"2026-02-11T01:51:32.701394Z","steps":["trace[1749178867] 'read index received' (duration: 135.396258ms)","trace[1749178867] 'applied index is now lower than readState.Index' (duration: 70.907078ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:32.701788Z","caller":"traceutil/trace.go:171","msg":"trace[1999137865] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"222.632092ms","start":"2026-02-11T01:51:32.47914Z","end":"2026-02-11T01:51:32.701772Z","steps":["trace[1999137865] 'process raft request' (duration: 151.339126ms)","trace[1999137865] 'compare' (duration: 70.778065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:32.701994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.574198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-11T01:51:32.702039Z","caller":"traceutil/trace.go:171","msg":"trace[264689560] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:401; }","duration":"221.64932ms","start":"2026-02-11T01:51:32.48038Z","end":"2026-02-11T01:51:32.702029Z","steps":["trace[264689560] 'agreement among raft nodes before linearized reading' (duration: 221.544128ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.709219Z","caller":"traceutil/trace.go:171","msg":"trace[571631439] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"210.40315ms","start":"2026-02-11T01:51:32.498799Z","end":"2026-02-11T01:51:32.709203Z","steps":["trace[571631439] 'process raft request' (duration: 210.273467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:32.709758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.811891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T01:51:32.709857Z","caller":"traceutil/trace.go:171","msg":"trace[275271835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"107.938093ms","start":"2026-02-11T01:51:32.601912Z","end":"2026-02-11T01:51:32.70985Z","steps":["trace[275271835] 'agreement among raft nodes before linearized reading' (duration: 107.81657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:33.154607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.607185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:33.15472Z","caller":"traceutil/trace.go:171","msg":"trace[1913666272] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"253.709901ms","start":"2026-02-11T01:51:32.900992Z","end":"2026-02-11T01:51:33.154702Z","steps":["trace[1913666272] 'process raft request' (duration: 42.882971ms)","trace[1913666272] 'compare' (duration: 210.499552ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:37.559629Z","caller":"traceutil/trace.go:171","msg":"trace[1215716715] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"102.464657ms","start":"2026-02-11T01:51:37.457147Z","end":"2026-02-11T01:51:37.559612Z","steps":["trace[1215716715] 'process raft request' (duration: 102.329114ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:43.758972Z","caller":"traceutil/trace.go:171","msg":"trace[1160659905] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"158.778708ms","start":"2026-02-11T01:51:43.600172Z","end":"2026-02-11T01:51:43.758951Z","steps":["trace[1160659905] 'read index received' (duration: 158.180124ms)","trace[1160659905] 'applied index is now lower than readState.Index' (duration: 597.724µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:43.759103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.911711ms","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-11T01:51:43.759136Z","caller":"traceutil/trace.go:171","msg":"trace[1276702388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:447; }","duration":"158.970333ms","start":"2026-02-11T01:51:43.600155Z","end":"2026-02-11T01:51:43.759126Z","steps":["trace[1276702388] 'agreement among raft nodes before linearized reading' (duration: 158.87523ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:43.759451Z","caller":"traceutil/trace.go:171","msg":"trace[1653890261] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"176.266042ms","start":"2026-02-11T01:51:43.583171Z","end":"2026-02-11T01:51:43.759437Z","steps":["trace[1653890261] 'process raft request' (duration: 175.443203ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:44.072865Z","caller":"traceutil/trace.go:171","msg":"trace[1922888987] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"118.297202ms","start":"2026-02-11T01:51:43.95453Z","end":"2026-02-11T01:51:44.072828Z","steps":["trace[1922888987] 'process raft request' (duration: 69.25661ms)","trace[1922888987] 'compare' (duration: 48.94428ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:44.074433Z","caller":"traceutil/trace.go:171","msg":"trace[2035855253] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"107.102044ms","start":"2026-02-11T01:51:43.967318Z","end":"2026-02-11T01:51:44.07442Z","steps":["trace[2035855253] 'process raft request' (duration: 106.785126ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:45.281533Z","caller":"traceutil/trace.go:171","msg":"trace[1588087334] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"136.475252ms","start":"2026-02-11T01:51:45.145036Z","end":"2026-02-11T01:51:45.281511Z","steps":["trace[1588087334] 'process raft request' (duration: 136.342949ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:47.153754Z","caller":"traceutil/trace.go:171","msg":"trace[202712048] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"143.561066ms","start":"2026-02-11T01:51:47.010177Z","end":"2026-02-11T01:51:47.153738Z","steps":["trace[202712048] 'read index received' (duration: 143.435623ms)","trace[202712048] 'applied index is now lower than readState.Index' (duration: 124.733µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:47.153872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.675699ms","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-11T01:51:47.1539Z","caller":"traceutil/trace.go:171","msg":"trace[1355215979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"143.741971ms","start":"2026-02-11T01:51:47.01015Z","end":"2026-02-11T01:51:47.153892Z","steps":["trace[1355215979] 'agreement among raft nodes before linearized reading' (duration: 143.651699ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:47.154075Z","caller":"traceutil/trace.go:171","msg":"trace[587649371] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"180.267334ms","start":"2026-02-11T01:51:46.973803Z","end":"2026-02-11T01:51:47.15407Z","steps":["trace[587649371] 'process raft request' (duration: 179.849714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:47.712088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.570866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:47.712179Z","caller":"traceutil/trace.go:171","msg":"trace[213531660] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"425.98248ms","start":"2026-02-11T01:51:47.286181Z","end":"2026-02-11T01:51:47.712164Z","steps":["trace[213531660] 'read index received' (duration: 205.228301ms)","trace[213531660] 'applied index is now lower than readState.Index' (duration: 220.753029ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:47.712276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.096904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:51:47.712305Z","caller":"traceutil/trace.go:171","msg":"trace[2052299465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"426.149894ms","start":"2026-02-11T01:51:47.286147Z","end":"2026-02-11T01:51:47.712296Z","steps":["trace[2052299465] 'agreement among raft nodes before linearized reading' (duration: 426.070202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:47.712336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:47.286118Z","time spent":"426.210666ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:51:47.712554Z","caller":"traceutil/trace.go:171","msg":"trace[1490349522] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"555.726018ms","start":"2026-02-11T01:51:47.156813Z","end":"2026-02-11T01:51:47.712539Z","steps":["trace[1490349522] 'process raft request' (duration: 334.6353ms)","trace[1490349522] 'compare' (duration: 220.430793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:47.712609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:47.156793Z","time spent":"555.785979ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:48.130285Z","caller":"traceutil/trace.go:171","msg":"trace[1240373173] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"232.312476ms","start":"2026-02-11T01:51:47.897956Z","end":"2026-02-11T01:51:48.130268Z","steps":["trace[1240373173] 'read index received' (duration: 232.194134ms)","trace[1240373173] 'applied index is now lower than readState.Index' (duration: 117.732µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:48.130433Z","caller":"traceutil/trace.go:171","msg":"trace[1658502153] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"410.682397ms","start":"2026-02-11T01:51:47.719734Z","end":"2026-02-11T01:51:48.130417Z","steps":["trace[1658502153] 'process raft request' (duration: 410.452131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:48.130499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:47.71972Z","time spent":"410.730509ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:48.130668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.715845ms","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-11T01:51:48.130691Z","caller":"traceutil/trace.go:171","msg":"trace[940515304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"232.772347ms","start":"2026-02-11T01:51:47.897912Z","end":"2026-02-11T01:51:48.130684Z","steps":["trace[940515304] 'agreement among raft nodes before linearized reading' (duration: 232.679305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:48.130786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.213003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:48.130801Z","caller":"traceutil/trace.go:171","msg":"trace[1862511338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"151.265685ms","start":"2026-02-11T01:51:47.979531Z","end":"2026-02-11T01:51:48.130797Z","steps":["trace[1862511338] 'agreement among raft nodes before linearized reading' (duration: 151.242004ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:49.845209Z","caller":"traceutil/trace.go:171","msg":"trace[132493775] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"123.179456ms","start":"2026-02-11T01:51:49.722014Z","end":"2026-02-11T01:51:49.845194Z","steps":["trace[132493775] 'process raft request' (duration: 123.081534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:50.310506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.425955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:50.310554Z","caller":"traceutil/trace.go:171","msg":"trace[1752796972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"334.517497ms","start":"2026-02-11T01:51:49.976025Z","end":"2026-02-11T01:51:50.310543Z","steps":["trace[1752796972] 'range keys from in-memory index tree' (duration: 334.325843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:50.310575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:49.976006Z","time spent":"334.564818ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:50.310786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.187658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:51:50.3108Z","caller":"traceutil/trace.go:171","msg":"trace[2029982720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"172.225428ms","start":"2026-02-11T01:51:50.13857Z","end":"2026-02-11T01:51:50.310796Z","steps":["trace[2029982720] 'range keys from in-memory index tree' (duration: 172.112446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:50.310866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.425197ms","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-11T01:51:50.310877Z","caller":"traceutil/trace.go:171","msg":"trace[393366604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"170.447907ms","start":"2026-02-11T01:51:50.140426Z","end":"2026-02-11T01:51:50.310873Z","steps":["trace[393366604] 'range keys from in-memory index tree' (duration: 170.402007ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:50.520252Z","caller":"traceutil/trace.go:171","msg":"trace[640187684] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"206.384627ms","start":"2026-02-11T01:51:50.313846Z","end":"2026-02-11T01:51:50.520231Z","steps":["trace[640187684] 'process raft request' (duration: 206.283735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.478715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449540181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:51:51.888885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.368663226s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:51:51.889198Z","caller":"traceutil/trace.go:171","msg":"trace[2076822135] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.574792028s","start":"2026-02-11T01:51:50.314386Z","end":"2026-02-11T01:51:51.889178Z","steps":["trace[2076822135] 'process raft request' (duration: 1.574670315s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.889305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:50.314379Z","time spent":"1.574868639s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:51.889663Z","caller":"traceutil/trace.go:171","msg":"trace[938292946] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:512; }","duration":"911.458485ms","start":"2026-02-11T01:51:50.978195Z","end":"2026-02-11T01:51:51.889653Z","steps":["trace[938292946] 'read index received' (duration: 911.455525ms)","trace[938292946] 'applied index is now lower than readState.Index' (duration: 2.38µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:51.889715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.526716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:51.889734Z","caller":"traceutil/trace.go:171","msg":"trace[1365446736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"911.575058ms","start":"2026-02-11T01:51:50.978153Z","end":"2026-02-11T01:51:51.889728Z","steps":["trace[1365446736] 'agreement among raft nodes before linearized reading' (duration: 911.542077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.889752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:50.978123Z","time spent":"911.625509ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:51.964263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.661358ms","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-11T01:51:51.964356Z","caller":"traceutil/trace.go:171","msg":"trace[1180255872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"114.813281ms","start":"2026-02-11T01:51:51.849525Z","end":"2026-02-11T01:51:51.964339Z","steps":["trace[1180255872] 'agreement among raft nodes before linearized reading' (duration: 114.647657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.964532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.46302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-11T01:51:51.96458Z","caller":"traceutil/trace.go:171","msg":"trace[542494939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"787.552071ms","start":"2026-02-11T01:51:51.177014Z","end":"2026-02-11T01:51:51.964566Z","steps":["trace[542494939] 'agreement among raft nodes before linearized reading' (duration: 787.418808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.964613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:51.176994Z","time spent":"787.612003ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:53.196632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.880797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:53.196711Z","caller":"traceutil/trace.go:171","msg":"trace[485246978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"215.992459ms","start":"2026-02-11T01:51:52.980693Z","end":"2026-02-11T01:51:53.196685Z","steps":["trace[485246978] 'range keys from in-memory index tree' (duration: 215.820526ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:53.659457Z","caller":"traceutil/trace.go:171","msg":"trace[1798934508] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"134.080678ms","start":"2026-02-11T01:51:53.525356Z","end":"2026-02-11T01:51:53.659437Z","steps":["trace[1798934508] 'process raft request' (duration: 133.960145ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:58.883876Z","caller":"traceutil/trace.go:171","msg":"trace[968793545] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"285.694599ms","start":"2026-02-11T01:51:58.598159Z","end":"2026-02-11T01:51:58.883853Z","steps":["trace[968793545] 'read index received' (duration: 285.528346ms)","trace[968793545] 'applied index is now lower than readState.Index' (duration: 165.383µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:58.884024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.846743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:51:58.884054Z","caller":"traceutil/trace.go:171","msg":"trace[798322406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"285.911485ms","start":"2026-02-11T01:51:58.598134Z","end":"2026-02-11T01:51:58.884045Z","steps":["trace[798322406] 'agreement among raft nodes before linearized reading' (duration: 285.825883ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:58.884391Z","caller":"traceutil/trace.go:171","msg":"trace[1684845428] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"433.162477ms","start":"2026-02-11T01:51:58.451217Z","end":"2026-02-11T01:51:58.88438Z","steps":["trace[1684845428] 'process raft request' (duration: 432.521062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:58.884495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:58.451206Z","time spent":"433.211897ms","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:59.487831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.91179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:59.488047Z","caller":"traceutil/trace.go:171","msg":"trace[462219555] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"184.538212ms","start":"2026-02-11T01:51:59.303489Z","end":"2026-02-11T01:51:59.488027Z","steps":["trace[462219555] 'process raft request' (duration: 184.467311ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:59.488074Z","caller":"traceutil/trace.go:171","msg":"trace[1806414120] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"594.543103ms","start":"2026-02-11T01:51:58.893511Z","end":"2026-02-11T01:51:59.488054Z","steps":["trace[1806414120] 'process raft request' (duration: 122.331626ms)","trace[1806414120] 'compare' (duration: 471.802668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:59.488149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:58.893487Z","time spent":"594.623834ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:01.803472Z","caller":"traceutil/trace.go:171","msg":"trace[1278383032] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"348.46457ms","start":"2026-02-11T01:52:01.454994Z","end":"2026-02-11T01:52:01.803459Z","steps":["trace[1278383032] 'process raft request' (duration: 348.389638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.803578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.454985Z","time spent":"348.553772ms","remote":"127.0.0.1:39006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:52:01.807549Z","caller":"traceutil/trace.go:171","msg":"trace[1855329808] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"336.131414ms","start":"2026-02-11T01:52:01.471407Z","end":"2026-02-11T01:52:01.807538Z","steps":["trace[1855329808] 'process raft request' (duration: 335.92957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.807635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.471404Z","time spent":"336.172196ms","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4807,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:01.807788Z","caller":"traceutil/trace.go:171","msg":"trace[1906443599] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"336.421342ms","start":"2026-02-11T01:52:01.47136Z","end":"2026-02-11T01:52:01.807781Z","steps":["trace[1906443599] 'process raft request' (duration: 335.91599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.807864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.471345Z","time spent":"336.457232ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:52:01.808051Z","caller":"traceutil/trace.go:171","msg":"trace[1583855449] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"336.609986ms","start":"2026-02-11T01:52:01.471434Z","end":"2026-02-11T01:52:01.808043Z","steps":["trace[1583855449] 'process raft request' (duration: 335.953541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.808088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.471431Z","time spent":"336.636317ms","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:01.808312Z","caller":"traceutil/trace.go:171","msg":"trace[1024470861] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"334.814264ms","start":"2026-02-11T01:52:01.473491Z","end":"2026-02-11T01:52:01.808305Z","steps":["trace[1024470861] 'process raft request' (duration: 333.928564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.808376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.473484Z","time spent":"334.845815ms","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:01.809734Z","caller":"traceutil/trace.go:171","msg":"trace[663466940] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:553; }","duration":"315.614001ms","start":"2026-02-11T01:52:01.494107Z","end":"2026-02-11T01:52:01.809721Z","steps":["trace[663466940] 'read index received' (duration: 309.378107ms)","trace[663466940] 'applied index is now lower than readState.Index' (duration: 6.235224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:01.810016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.901447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:52:01.810052Z","caller":"traceutil/trace.go:171","msg":"trace[1896948649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"315.962099ms","start":"2026-02-11T01:52:01.494079Z","end":"2026-02-11T01:52:01.810041Z","steps":["trace[1896948649] 'agreement among raft nodes before linearized reading' (duration: 315.902298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.810081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.494065Z","time spent":"316.00875ms","remote":"127.0.0.1:57828","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-11T01:52:01.810217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.264541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:01.810247Z","caller":"traceutil/trace.go:171","msg":"trace[1992538628] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:540; }","duration":"221.320613ms","start":"2026-02-11T01:52:01.588916Z","end":"2026-02-11T01:52:01.810237Z","steps":["trace[1992538628] 'agreement among raft nodes before linearized reading' (duration: 221.282232ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:06.069308Z","caller":"traceutil/trace.go:171","msg":"trace[1934363168] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"278.734008ms","start":"2026-02-11T01:52:05.790563Z","end":"2026-02-11T01:52:06.069297Z","steps":["trace[1934363168] 'read index received' (duration: 278.534144ms)","trace[1934363168] 'applied index is now lower than readState.Index' (duration: 199.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:06.069799Z","caller":"traceutil/trace.go:171","msg":"trace[2084287549] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"393.356887ms","start":"2026-02-11T01:52:05.676312Z","end":"2026-02-11T01:52:06.069669Z","steps":["trace[2084287549] 'process raft request' (duration: 392.868156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:06.069926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:05.676296Z","time spent":"393.570821ms","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:06.070193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.64881ms","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-11T01:52:06.070287Z","caller":"traceutil/trace.go:171","msg":"trace[885173404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:600; }","duration":"279.775953ms","start":"2026-02-11T01:52:05.790503Z","end":"2026-02-11T01:52:06.070279Z","steps":["trace[885173404] 'agreement among raft nodes before linearized reading' (duration: 279.607369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:06.070575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.803478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:52:06.070649Z","caller":"traceutil/trace.go:171","msg":"trace[834328933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"184.956792ms","start":"2026-02-11T01:52:05.885687Z","end":"2026-02-11T01:52:06.070644Z","steps":["trace[834328933] 'agreement among raft nodes before linearized reading' (duration: 184.804179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:07.168954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.567252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:07.169018Z","caller":"traceutil/trace.go:171","msg":"trace[274196151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"193.665713ms","start":"2026-02-11T01:52:06.975339Z","end":"2026-02-11T01:52:07.169005Z","steps":["trace[274196151] 'range keys from in-memory index tree' (duration: 193.498949ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:10.474392Z","caller":"traceutil/trace.go:171","msg":"trace[656282123] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"359.599794ms","start":"2026-02-11T01:52:10.114771Z","end":"2026-02-11T01:52:10.474371Z","steps":["trace[656282123] 'read index received' (duration: 359.44306ms)","trace[656282123] 'applied index is now lower than readState.Index' (duration: 155.854µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:10.474679Z","caller":"traceutil/trace.go:171","msg":"trace[530320362] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"360.433784ms","start":"2026-02-11T01:52:10.114235Z","end":"2026-02-11T01:52:10.474668Z","steps":["trace[530320362] 'process raft request' (duration: 360.032444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.474774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.114223Z","time spent":"360.489935ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:10.474991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.214668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:52:10.475021Z","caller":"traceutil/trace.go:171","msg":"trace[2060900881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"360.25429ms","start":"2026-02-11T01:52:10.114757Z","end":"2026-02-11T01:52:10.475012Z","steps":["trace[2060900881] 'agreement among raft nodes before linearized reading' (duration: 360.152167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.475047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.114752Z","time spent":"360.289541ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:10.478577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.573951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T01:52:10.478618Z","caller":"traceutil/trace.go:171","msg":"trace[148995381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"361.627012ms","start":"2026-02-11T01:52:10.116982Z","end":"2026-02-11T01:52:10.478609Z","steps":["trace[148995381] 'agreement among raft nodes before linearized reading' (duration: 361.498409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.478643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.116977Z","time spent":"361.661163ms","remote":"127.0.0.1:57828","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-11T01:52:10.478894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.160166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-11T01:52:10.478931Z","caller":"traceutil/trace.go:171","msg":"trace[1783151551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"339.210898ms","start":"2026-02-11T01:52:10.139714Z","end":"2026-02-11T01:52:10.478924Z","steps":["trace[1783151551] 'agreement among raft nodes before linearized reading' (duration: 339.111426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.478949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.139702Z","time spent":"339.243029ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:52:10.864987Z","caller":"traceutil/trace.go:171","msg":"trace[1941575530] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"373.54134ms","start":"2026-02-11T01:52:10.491414Z","end":"2026-02-11T01:52:10.864955Z","steps":["trace[1941575530] 'process raft request' (duration: 275.834547ms)","trace[1941575530] 'compare' (duration: 97.462197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:10.865129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.490267Z","time spent":"374.778789ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:10.887405Z","caller":"traceutil/trace.go:171","msg":"trace[2029933934] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"328.988969ms","start":"2026-02-11T01:52:10.558391Z","end":"2026-02-11T01:52:10.88738Z","steps":["trace[2029933934] 'read index received' (duration: 208.866232ms)","trace[2029933934] 'applied index is now lower than readState.Index' (duration: 120.119877ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:10.887425Z","caller":"traceutil/trace.go:171","msg":"trace[1928910431] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"393.939517ms","start":"2026-02-11T01:52:10.493466Z","end":"2026-02-11T01:52:10.887405Z","steps":["trace[1928910431] 'process raft request' (duration: 393.743772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.887593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.49346Z","time spent":"394.029299ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:10.887743Z","caller":"traceutil/trace.go:171","msg":"trace[1909733399] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"394.173992ms","start":"2026-02-11T01:52:10.493539Z","end":"2026-02-11T01:52:10.887713Z","steps":["trace[1909733399] 'process raft request' (duration: 393.800723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.887832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.493496Z","time spent":"394.285224ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:10.887986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.596673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qvmxb\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-11T01:52:10.888022Z","caller":"traceutil/trace.go:171","msg":"trace[1495566273] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qvmxb; range_end:; response_count:1; response_revision:627; }","duration":"329.658454ms","start":"2026-02-11T01:52:10.558353Z","end":"2026-02-11T01:52:10.888011Z","steps":["trace[1495566273] 'agreement among raft nodes before linearized reading' (duration: 329.531881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.888052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.558339Z","time spent":"329.706986ms","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-qvmxb\" "} {"level":"warn","ts":"2026-02-11T01:52:11.478124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449540451,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:52:11.909803Z","caller":"traceutil/trace.go:171","msg":"trace[635965876] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.005627193s","start":"2026-02-11T01:52:10.904155Z","end":"2026-02-11T01:52:11.909783Z","steps":["trace[635965876] 'process raft request' (duration: 1.00550477s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:11.910051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.904134Z","time spent":"1.005730046s","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:11.915661Z","caller":"traceutil/trace.go:171","msg":"trace[444908303] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:649; }","duration":"937.769117ms","start":"2026-02-11T01:52:10.977878Z","end":"2026-02-11T01:52:11.915647Z","steps":["trace[444908303] 'read index received' (duration: 937.763817ms)","trace[444908303] 'applied index is now lower than readState.Index' (duration: 4.22µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:11.915851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.973851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:11.915921Z","caller":"traceutil/trace.go:171","msg":"trace[1096548283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"938.111355ms","start":"2026-02-11T01:52:10.977799Z","end":"2026-02-11T01:52:11.91591Z","steps":["trace[1096548283] 'agreement among raft nodes before linearized reading' (duration: 938.016413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:11.915984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.977785Z","time spent":"938.160266ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:13.681396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34404121s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T01:52:13.681496Z","caller":"traceutil/trace.go:171","msg":"trace[1901540899] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"1.765744326s","start":"2026-02-11T01:52:11.915734Z","end":"2026-02-11T01:52:13.681478Z","steps":["trace[1901540899] 'read index received' (duration: 421.567172ms)","trace[1901540899] 'applied index is now lower than readState.Index' (duration: 1.344175364s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:13.681694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.134485913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-11T01:52:13.68169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763470733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:13.68172Z","caller":"traceutil/trace.go:171","msg":"trace[2097018554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.134556665s","start":"2026-02-11T01:52:11.547155Z","end":"2026-02-11T01:52:13.681712Z","steps":["trace[2097018554] 'agreement among raft nodes before linearized reading' (duration: 2.134425012s)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:13.681739Z","caller":"traceutil/trace.go:171","msg":"trace[1879119455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.763549824s","start":"2026-02-11T01:52:11.918175Z","end":"2026-02-11T01:52:13.681725Z","steps":["trace[1879119455] 'agreement among raft nodes before linearized reading' (duration: 1.763468123s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.681757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:11.547136Z","time spent":"2.134611057s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:13.681847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.384527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-11T01:52:13.681862Z","caller":"traceutil/trace.go:171","msg":"trace[1120430993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"786.420559ms","start":"2026-02-11T01:52:12.895437Z","end":"2026-02-11T01:52:13.681857Z","steps":["trace[1120430993] 'agreement among raft nodes before linearized reading' (duration: 786.339547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.681874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.895411Z","time spent":"786.45989ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:13.681951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.548875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:52:13.681967Z","caller":"traceutil/trace.go:171","msg":"trace[268491837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"787.591456ms","start":"2026-02-11T01:52:12.894372Z","end":"2026-02-11T01:52:13.681963Z","steps":["trace[268491837] 'agreement among raft nodes before linearized reading' (duration: 787.554205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.681978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.894358Z","time spent":"787.616916ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:13.68203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.073092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T01:52:13.682041Z","caller":"traceutil/trace.go:171","msg":"trace[1942231739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"810.118922ms","start":"2026-02-11T01:52:12.871918Z","end":"2026-02-11T01:52:13.682037Z","steps":["trace[1942231739] 'agreement among raft nodes before linearized reading' (duration: 810.085301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.682052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.871898Z","time spent":"810.151393ms","remote":"127.0.0.1:57828","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-11T01:52:13.681764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:11.91816Z","time spent":"1.763600036s","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:13.682154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193593664s","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-11T01:52:13.682168Z","caller":"traceutil/trace.go:171","msg":"trace[728251829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"1.193641636s","start":"2026-02-11T01:52:12.488523Z","end":"2026-02-11T01:52:13.682164Z","steps":["trace[728251829] 'agreement among raft nodes before linearized reading' (duration: 1.193601705s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.682189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.488506Z","time spent":"1.193671666s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:52:13.682298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763103705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qvmxb\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-11T01:52:13.682311Z","caller":"traceutil/trace.go:171","msg":"trace[1333450710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qvmxb; range_end:; response_count:1; response_revision:628; }","duration":"1.763126585s","start":"2026-02-11T01:52:11.919181Z","end":"2026-02-11T01:52:13.682308Z","steps":["trace[1333450710] 'agreement among raft nodes before linearized reading' (duration: 1.763082544s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.682322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:11.919176Z","time spent":"1.763143645s","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-qvmxb\" "} {"level":"warn","ts":"2026-02-11T01:52:14.19246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449540456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:52:14.692983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449540456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:52:14.747055Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055828366s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:52:14.747486Z","caller":"traceutil/trace.go:171","msg":"trace[1714729940] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.056280316s","start":"2026-02-11T01:52:13.691161Z","end":"2026-02-11T01:52:14.747442Z","steps":["trace[1714729940] 'process raft request' (duration: 1.056159263s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:14.747637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.691146Z","time spent":"1.056407309s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:15.692684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000348791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:52:15.692793Z","caller":"traceutil/trace.go:171","msg":"trace[390160399] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000505274s","start":"2026-02-11T01:52:13.69227Z","end":"2026-02-11T01:52:15.692775Z","steps":["trace[390160399] 'agreement among raft nodes before linearized reading' (duration: 2.00036143s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.69286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.692262Z","time spent":"2.000588096s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:16.020318Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.273048792s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:52:16.566427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.714119ms","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-11T01:52:16.572057Z","caller":"traceutil/trace.go:171","msg":"trace[421639257] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:629; }","duration":"339.355521ms","start":"2026-02-11T01:52:16.232685Z","end":"2026-02-11T01:52:16.57204Z","steps":["trace[421639257] 'range keys from in-memory index tree' (duration: 333.700289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.573058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.544663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:16.573284Z","caller":"traceutil/trace.go:171","msg":"trace[1154549578] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:651; }","duration":"2.880984542s","start":"2026-02-11T01:52:13.69229Z","end":"2026-02-11T01:52:16.573274Z","steps":["trace[1154549578] 'read index received' (duration: 1.055341554s)","trace[1154549578] 'applied index is now lower than readState.Index' (duration: 1.825642328s)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:16.573745Z","caller":"traceutil/trace.go:171","msg":"trace[1651085023] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"2.877737165s","start":"2026-02-11T01:52:13.695996Z","end":"2026-02-11T01:52:16.573734Z","steps":["trace[1651085023] 'process raft request' (duration: 2.877190683s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.575024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.69599Z","time spent":"2.877779106s","remote":"127.0.0.1:39124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.57525Z","caller":"traceutil/trace.go:171","msg":"trace[2044712362] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.882919477s","start":"2026-02-11T01:52:13.692318Z","end":"2026-02-11T01:52:16.575238Z","steps":["trace[2044712362] 'process raft request' (duration: 2.328145021s)","trace[2044712362] 'compare' (duration: 544.982687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:16.575292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.692314Z","time spent":"2.882955097s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.575343Z","caller":"traceutil/trace.go:171","msg":"trace[369808895] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.882032206s","start":"2026-02-11T01:52:13.693305Z","end":"2026-02-11T01:52:16.575337Z","steps":["trace[369808895] 'process raft request' (duration: 2.879804884s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.575373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.693298Z","time spent":"2.882060106s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.575419Z","caller":"traceutil/trace.go:171","msg":"trace[1968344994] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.881824141s","start":"2026-02-11T01:52:13.69359Z","end":"2026-02-11T01:52:16.575414Z","steps":["trace[1968344994] 'process raft request' (duration: 2.879565418s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.575445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.693583Z","time spent":"2.881847951s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.575807Z","caller":"traceutil/trace.go:171","msg":"trace[652761311] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.431255591s","start":"2026-02-11T01:52:14.144543Z","end":"2026-02-11T01:52:16.575798Z","steps":["trace[652761311] 'process raft request' (duration: 2.428699621s)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:16.575796Z","caller":"traceutil/trace.go:171","msg":"trace[1261491116] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.878493643s","start":"2026-02-11T01:52:13.697277Z","end":"2026-02-11T01:52:16.57577Z","steps":["trace[1261491116] 'process raft request' (duration: 2.875940133s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.575848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.144519Z","time spent":"2.431307062s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:16.575925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.697269Z","time spent":"2.878584095s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:16.575944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.719936038s","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-11T01:52:16.575963Z","caller":"traceutil/trace.go:171","msg":"trace[564504331] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:635; }","duration":"2.719992999s","start":"2026-02-11T01:52:13.855965Z","end":"2026-02-11T01:52:16.575958Z","steps":["trace[564504331] 'agreement among raft nodes before linearized reading' (duration: 2.719959099s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.575982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.855944Z","time spent":"2.72003393s","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:16.576228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.860144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:52:16.576248Z","caller":"traceutil/trace.go:171","msg":"trace[1850790524] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:635; }","duration":"369.915855ms","start":"2026-02-11T01:52:16.206327Z","end":"2026-02-11T01:52:16.576243Z","steps":["trace[1850790524] 'agreement among raft nodes before linearized reading' (duration: 369.840093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.576265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.206308Z","time spent":"369.954097ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-02-11T01:52:16.576351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.936094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:16.576367Z","caller":"traceutil/trace.go:171","msg":"trace[1725529275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"882.981036ms","start":"2026-02-11T01:52:15.69338Z","end":"2026-02-11T01:52:16.576361Z","steps":["trace[1725529275] 'agreement among raft nodes before linearized reading' (duration: 882.955325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.576382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.693365Z","time spent":"883.013736ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:16.576478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.360193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:16.576508Z","caller":"traceutil/trace.go:171","msg":"trace[1088942681] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:635; }","duration":"2.360256002s","start":"2026-02-11T01:52:14.216243Z","end":"2026-02-11T01:52:16.576499Z","steps":["trace[1088942681] 'agreement among raft nodes before linearized reading' (duration: 2.360198371s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.57653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.216222Z","time spent":"2.360303033s","remote":"127.0.0.1:57956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:16.951915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.320623ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-11T01:52:16.952064Z","caller":"traceutil/trace.go:171","msg":"trace[697595290] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"362.923102ms","start":"2026-02-11T01:52:16.589122Z","end":"2026-02-11T01:52:16.952045Z","steps":["trace[697595290] 'read index received' (duration: 212.607679ms)","trace[697595290] 'applied index is now lower than readState.Index' (duration: 150.313523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:16.952176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.583703Z","time spent":"368.467102ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:52:16.952246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.112936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:16.952511Z","caller":"traceutil/trace.go:171","msg":"trace[1134825475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"363.400664ms","start":"2026-02-11T01:52:16.5891Z","end":"2026-02-11T01:52:16.952501Z","steps":["trace[1134825475] 'agreement among raft nodes before linearized reading' (duration: 363.106287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.952557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.589093Z","time spent":"363.454654ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:52:16.954965Z","caller":"traceutil/trace.go:171","msg":"trace[2000201640] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"355.657572ms","start":"2026-02-11T01:52:16.599284Z","end":"2026-02-11T01:52:16.954941Z","steps":["trace[2000201640] 'process raft request' (duration: 355.618771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.955093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.599264Z","time spent":"355.737523ms","remote":"127.0.0.1:39108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4129,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.955423Z","caller":"traceutil/trace.go:171","msg":"trace[1443458780] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"366.243199ms","start":"2026-02-11T01:52:16.58917Z","end":"2026-02-11T01:52:16.955413Z","steps":["trace[1443458780] 'process raft request' (duration: 365.598884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.955493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.589161Z","time spent":"366.3069ms","remote":"127.0.0.1:39006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:52:16.956083Z","caller":"traceutil/trace.go:171","msg":"trace[1198548336] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"359.607593ms","start":"2026-02-11T01:52:16.596466Z","end":"2026-02-11T01:52:16.956074Z","steps":["trace[1198548336] 'process raft request' (duration: 358.382054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.956154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.596454Z","time spent":"359.654745ms","remote":"127.0.0.1:58036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:16.958464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.372516ms","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-11T01:52:16.963568Z","caller":"traceutil/trace.go:171","msg":"trace[234205897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"203.218578ms","start":"2026-02-11T01:52:16.75998Z","end":"2026-02-11T01:52:16.963199Z","steps":["trace[234205897] 'agreement among raft nodes before linearized reading' (duration: 198.423946ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:18.693743Z","caller":"traceutil/trace.go:171","msg":"trace[349530553] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"105.202679ms","start":"2026-02-11T01:52:18.588518Z","end":"2026-02-11T01:52:18.693721Z","steps":["trace[349530553] 'process raft request' (duration: 104.734008ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:19.489454Z","caller":"traceutil/trace.go:171","msg":"trace[93527314] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"120.572438ms","start":"2026-02-11T01:52:19.368865Z","end":"2026-02-11T01:52:19.489438Z","steps":["trace[93527314] 'process raft request' (duration: 120.392544ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:19.503939Z","caller":"traceutil/trace.go:171","msg":"trace[934893447] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"125.102903ms","start":"2026-02-11T01:52:19.378824Z","end":"2026-02-11T01:52:19.503927Z","steps":["trace[934893447] 'process raft request' (duration: 125.016241ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:19.789879Z","caller":"traceutil/trace.go:171","msg":"trace[2140728196] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"197.471245ms","start":"2026-02-11T01:52:19.592392Z","end":"2026-02-11T01:52:19.789863Z","steps":["trace[2140728196] 'process raft request' (duration: 197.283921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:20.772553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.896177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:20.772621Z","caller":"traceutil/trace.go:171","msg":"trace[371437429] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:663; }","duration":"742.039121ms","start":"2026-02-11T01:52:20.030565Z","end":"2026-02-11T01:52:20.772605Z","steps":["trace[371437429] 'count revisions from in-memory index tree' (duration: 741.781645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:20.77267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:20.030551Z","time spent":"742.094682ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:20.773263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.125591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:20.77348Z","caller":"traceutil/trace.go:171","msg":"trace[557362544] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"718.711986ms","start":"2026-02-11T01:52:20.054756Z","end":"2026-02-11T01:52:20.773468Z","steps":["trace[557362544] 'process raft request' (duration: 401.324059ms)","trace[557362544] 'compare' (duration: 316.879435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:20.773527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:20.054744Z","time spent":"718.757697ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:20.953295Z","caller":"traceutil/trace.go:171","msg":"trace[119893273] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"170.75638ms","start":"2026-02-11T01:52:20.78252Z","end":"2026-02-11T01:52:20.953277Z","steps":["trace[119893273] 'process raft request' (duration: 170.699509ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:20.953425Z","caller":"traceutil/trace.go:171","msg":"trace[33077225] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"170.939025ms","start":"2026-02-11T01:52:20.78248Z","end":"2026-02-11T01:52:20.953419Z","steps":["trace[33077225] 'process raft request' (duration: 170.644258ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:20.953499Z","caller":"traceutil/trace.go:171","msg":"trace[475452767] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"170.959375ms","start":"2026-02-11T01:52:20.782523Z","end":"2026-02-11T01:52:20.953483Z","steps":["trace[475452767] 'process raft request' (duration: 170.718689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:22.071639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.854631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:22.071692Z","caller":"traceutil/trace.go:171","msg":"trace[1253241159] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:668; }","duration":"484.990494ms","start":"2026-02-11T01:52:21.58669Z","end":"2026-02-11T01:52:22.071681Z","steps":["trace[1253241159] 'count revisions from in-memory index tree' (duration: 484.79515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:22.071717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:21.586676Z","time spent":"485.035865ms","remote":"127.0.0.1:39142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:22.072064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.866469ms","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-11T01:52:22.07208Z","caller":"traceutil/trace.go:171","msg":"trace[1406617325] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:668; }","duration":"851.90893ms","start":"2026-02-11T01:52:21.220166Z","end":"2026-02-11T01:52:22.072075Z","steps":["trace[1406617325] 'count revisions from in-memory index tree' (duration: 851.813227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:22.072095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:21.22015Z","time spent":"851.94096ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:22.072078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.960572ms","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-11T01:52:22.072133Z","caller":"traceutil/trace.go:171","msg":"trace[2029040876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"971.021584ms","start":"2026-02-11T01:52:21.101096Z","end":"2026-02-11T01:52:22.072117Z","steps":["trace[2029040876] 'range keys from in-memory index tree' (duration: 970.815709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:22.072164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:21.101079Z","time spent":"971.079924ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T01:52:22.462905Z","caller":"traceutil/trace.go:171","msg":"trace[1374243125] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"388.195152ms","start":"2026-02-11T01:52:22.074694Z","end":"2026-02-11T01:52:22.46289Z","steps":["trace[1374243125] 'process raft request' (duration: 388.10131ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:22.462979Z","caller":"traceutil/trace.go:171","msg":"trace[1447316916] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"485.907486ms","start":"2026-02-11T01:52:21.977052Z","end":"2026-02-11T01:52:22.462959Z","steps":["trace[1447316916] 'read index received' (duration: 431.340651ms)","trace[1447316916] 'applied index is now lower than readState.Index' (duration: 54.565635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:22.463008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:22.07468Z","time spent":"388.273595ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:22.463066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.009878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:22.463085Z","caller":"traceutil/trace.go:171","msg":"trace[1944142634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"486.06072ms","start":"2026-02-11T01:52:21.977018Z","end":"2026-02-11T01:52:22.463079Z","steps":["trace[1944142634] 'agreement among raft nodes before linearized reading' (duration: 485.997578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:22.463101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:21.977006Z","time spent":"486.09094ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:22.997896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.349173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:22.997956Z","caller":"traceutil/trace.go:171","msg":"trace[1745213166] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:669; }","duration":"212.473806ms","start":"2026-02-11T01:52:22.78547Z","end":"2026-02-11T01:52:22.997943Z","steps":["trace[1745213166] 'count revisions from in-memory index tree' (duration: 212.294402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:22.998199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.787391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T01:52:22.99824Z","caller":"traceutil/trace.go:171","msg":"trace[512647665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"158.852013ms","start":"2026-02-11T01:52:22.839378Z","end":"2026-02-11T01:52:22.99823Z","steps":["trace[512647665] 'range keys from in-memory index tree' (duration: 158.731399ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:23.264877Z","caller":"traceutil/trace.go:171","msg":"trace[1624756760] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"260.914318ms","start":"2026-02-11T01:52:23.003948Z","end":"2026-02-11T01:52:23.264862Z","steps":["trace[1624756760] 'process raft request' (duration: 260.819356ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:23.267455Z","caller":"traceutil/trace.go:171","msg":"trace[1724511076] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"263.410755ms","start":"2026-02-11T01:52:23.004036Z","end":"2026-02-11T01:52:23.267447Z","steps":["trace[1724511076] 'process raft request' (duration: 263.235871ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:23.267574Z","caller":"traceutil/trace.go:171","msg":"trace[409730700] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"263.505848ms","start":"2026-02-11T01:52:23.004063Z","end":"2026-02-11T01:52:23.267569Z","steps":["trace[409730700] 'process raft request' (duration: 263.253092ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:23.267666Z","caller":"traceutil/trace.go:171","msg":"trace[1926199409] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"263.544299ms","start":"2026-02-11T01:52:23.004117Z","end":"2026-02-11T01:52:23.267661Z","steps":["trace[1926199409] 'process raft request' (duration: 263.217562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:32.333148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.471932ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T01:52:33.539425Z","caller":"traceutil/trace.go:171","msg":"trace[1961464391] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"184.659055ms","start":"2026-02-11T01:52:33.354746Z","end":"2026-02-11T01:52:33.539405Z","steps":["trace[1961464391] 'process raft request' (duration: 184.514082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:34.733833Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125233138s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:52:34.73406Z","caller":"traceutil/trace.go:171","msg":"trace[1212467327] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"1.37865495s","start":"2026-02-11T01:52:33.355387Z","end":"2026-02-11T01:52:34.734042Z","steps":["trace[1212467327] 'read index received' (duration: 184.479221ms)","trace[1212467327] 'applied index is now lower than readState.Index' (duration: 1.194174999s)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:34.734116Z","caller":"traceutil/trace.go:171","msg":"trace[1071763505] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.379259435s","start":"2026-02-11T01:52:33.354847Z","end":"2026-02-11T01:52:34.734107Z","steps":["trace[1071763505] 'process raft request' (duration: 1.379097751s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:34.734214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:33.354801Z","time spent":"1.379339437s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:34.734333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376008419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:52:34.734376Z","caller":"traceutil/trace.go:171","msg":"trace[723061819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"1.37607102s","start":"2026-02-11T01:52:33.358293Z","end":"2026-02-11T01:52:34.734364Z","steps":["trace[723061819] 'agreement among raft nodes before linearized reading' (duration: 1.375955247s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:34.734397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:33.358288Z","time spent":"1.37610371s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:34.734472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37908456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:52:34.734483Z","caller":"traceutil/trace.go:171","msg":"trace[1277062610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"1.37909509s","start":"2026-02-11T01:52:33.355384Z","end":"2026-02-11T01:52:34.734479Z","steps":["trace[1277062610] 'agreement among raft nodes before linearized reading' (duration: 1.37905951s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:34.734494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:33.355353Z","time spent":"1.379137922s","remote":"127.0.0.1:57828","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-11T01:52:34.734662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.728352ms","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-11T01:52:34.734686Z","caller":"traceutil/trace.go:171","msg":"trace[1357681434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:734; }","duration":"218.784013ms","start":"2026-02-11T01:52:34.515895Z","end":"2026-02-11T01:52:34.734679Z","steps":["trace[1357681434] 'agreement among raft nodes before linearized reading' (duration: 218.708981ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:34.88857Z","caller":"traceutil/trace.go:171","msg":"trace[1857416937] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"149.264397ms","start":"2026-02-11T01:52:34.739283Z","end":"2026-02-11T01:52:34.888548Z","steps":["trace[1857416937] 'process raft request' (duration: 149.219506ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:34.888767Z","caller":"traceutil/trace.go:171","msg":"trace[1761947275] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"149.546884ms","start":"2026-02-11T01:52:34.73921Z","end":"2026-02-11T01:52:34.888756Z","steps":["trace[1761947275] 'process raft request' (duration: 127.523519ms)","trace[1761947275] 'compare' (duration: 21.652876ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:41.21747Z","caller":"traceutil/trace.go:171","msg":"trace[264416030] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"385.635613ms","start":"2026-02-11T01:52:40.831823Z","end":"2026-02-11T01:52:41.217459Z","steps":["trace[264416030] 'process raft request' (duration: 385.563261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.217559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:40.831813Z","time spent":"385.696244ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:41.217771Z","caller":"traceutil/trace.go:171","msg":"trace[1021822298] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:785; }","duration":"370.892928ms","start":"2026-02-11T01:52:40.846873Z","end":"2026-02-11T01:52:41.217766Z","steps":["trace[1021822298] 'read index received' (duration: 370.890838ms)","trace[1021822298] 'applied index is now lower than readState.Index' (duration: 1.77µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:41.217855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.97995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:52:41.217879Z","caller":"traceutil/trace.go:171","msg":"trace[10661871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"371.045731ms","start":"2026-02-11T01:52:40.846827Z","end":"2026-02-11T01:52:41.217872Z","steps":["trace[10661871] 'agreement among raft nodes before linearized reading' (duration: 370.97004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.217912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:40.846811Z","time spent":"371.085913ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:52:41.589578Z","caller":"traceutil/trace.go:171","msg":"trace[1727066198] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"371.773059ms","start":"2026-02-11T01:52:41.217788Z","end":"2026-02-11T01:52:41.589561Z","steps":["trace[1727066198] 'read index received' (duration: 355.042778ms)","trace[1727066198] 'applied index is now lower than readState.Index' (duration: 16.729491ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:41.589646Z","caller":"traceutil/trace.go:171","msg":"trace[1754473383] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"607.483377ms","start":"2026-02-11T01:52:40.982156Z","end":"2026-02-11T01:52:41.589639Z","steps":["trace[1754473383] 'process raft request' (duration: 590.666434ms)","trace[1754473383] 'compare' (duration: 16.046465ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:41.589631Z","caller":"traceutil/trace.go:171","msg":"trace[1715204094] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"369.225049ms","start":"2026-02-11T01:52:41.22038Z","end":"2026-02-11T01:52:41.589605Z","steps":["trace[1715204094] 'process raft request' (duration: 369.076146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.589715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:40.982148Z","time spent":"607.519779ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:41.589726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:41.220369Z","time spent":"369.307731ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:41.596198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.119753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:41.596236Z","caller":"traceutil/trace.go:171","msg":"trace[1151611219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"620.188005ms","start":"2026-02-11T01:52:40.976038Z","end":"2026-02-11T01:52:41.596226Z","steps":["trace[1151611219] 'agreement among raft nodes before linearized reading' (duration: 613.584031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.596254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:40.976026Z","time spent":"620.224585ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:41.596312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.558116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:52:41.596385Z","caller":"traceutil/trace.go:171","msg":"trace[1215744137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"615.614678ms","start":"2026-02-11T01:52:40.980727Z","end":"2026-02-11T01:52:41.596342Z","steps":["trace[1215744137] 'agreement among raft nodes before linearized reading' (duration: 609.062104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.596412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:40.980723Z","time spent":"615.683179ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:41.596498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.84043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:41.596516Z","caller":"traceutil/trace.go:171","msg":"trace[40000990] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:758; }","duration":"439.88517ms","start":"2026-02-11T01:52:41.156626Z","end":"2026-02-11T01:52:41.596511Z","steps":["trace[40000990] 'agreement among raft nodes before linearized reading' (duration: 433.171824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.596531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:41.156611Z","time spent":"439.915791ms","remote":"127.0.0.1:57878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:41.596562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.634485ms","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-11T01:52:41.596582Z","caller":"traceutil/trace.go:171","msg":"trace[229610480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"617.663695ms","start":"2026-02-11T01:52:40.978913Z","end":"2026-02-11T01:52:41.596576Z","steps":["trace[229610480] 'agreement among raft nodes before linearized reading' (duration: 610.893187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:41.596602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:40.978906Z","time spent":"617.689455ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T01:52:55.451787Z","caller":"traceutil/trace.go:171","msg":"trace[372327134] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"178.043803ms","start":"2026-02-11T01:52:55.273726Z","end":"2026-02-11T01:52:55.45177Z","steps":["trace[372327134] 'process raft request' (duration: 177.949421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.761026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.018114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-11T01:52:56.761092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092447351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T01:52:56.761096Z","caller":"traceutil/trace.go:171","msg":"trace[1793115668] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:846; }","duration":"553.082665ms","start":"2026-02-11T01:52:56.207978Z","end":"2026-02-11T01:52:56.761061Z","steps":["trace[1793115668] 'range keys from in-memory index tree' (duration: 552.932742ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:56.76112Z","caller":"traceutil/trace.go:171","msg":"trace[1590661881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:846; }","duration":"1.092493771s","start":"2026-02-11T01:52:55.668617Z","end":"2026-02-11T01:52:56.761111Z","steps":["trace[1590661881] 'range keys from in-memory index tree' (duration: 1.092370199s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.761133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.207966Z","time spent":"553.156838ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-02-11T01:52:56.761142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:55.668605Z","time spent":"1.092531323s","remote":"127.0.0.1:57828","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-11T01:52:56.761319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090404743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:52:56.761346Z","caller":"traceutil/trace.go:171","msg":"trace[1129161217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"1.090443974s","start":"2026-02-11T01:52:55.670894Z","end":"2026-02-11T01:52:56.761338Z","steps":["trace[1129161217] 'range keys from in-memory index tree' (duration: 1.090339441s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.761357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.30797ms","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-11T01:52:56.761457Z","caller":"traceutil/trace.go:171","msg":"trace[604887951] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:846; }","duration":"529.407892ms","start":"2026-02-11T01:52:56.232038Z","end":"2026-02-11T01:52:56.761446Z","steps":["trace[604887951] 'range keys from in-memory index tree' (duration: 529.29956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.761485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088959319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:52:56.761513Z","caller":"traceutil/trace.go:171","msg":"trace[592644348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:846; }","duration":"1.08899581s","start":"2026-02-11T01:52:55.672509Z","end":"2026-02-11T01:52:56.761505Z","steps":["trace[592644348] 'range keys from in-memory index tree' (duration: 1.088908918s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.761536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:55.672503Z","time spent":"1.089027421s","remote":"127.0.0.1:57828","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-11T01:52:56.761718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.301014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:56.76175Z","caller":"traceutil/trace.go:171","msg":"trace[1243064742] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:846; }","duration":"462.368445ms","start":"2026-02-11T01:52:56.299372Z","end":"2026-02-11T01:52:56.76174Z","steps":["trace[1243064742] 'count revisions from in-memory index tree' (duration: 462.213472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.761778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.29935Z","time spent":"462.418657ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:56.761968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.872948ms","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-11T01:52:56.761997Z","caller":"traceutil/trace.go:171","msg":"trace[1281687188] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:846; }","duration":"302.94118ms","start":"2026-02-11T01:52:56.459047Z","end":"2026-02-11T01:52:56.761988Z","steps":["trace[1281687188] 'count revisions from in-memory index tree' (duration: 302.780926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.762024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.459028Z","time spent":"302.986781ms","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:56.761379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:55.670883Z","time spent":"1.090480745s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:56.761009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098379809s","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-11T01:52:56.762153Z","caller":"traceutil/trace.go:171","msg":"trace[1921011083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:846; }","duration":"1.099560416s","start":"2026-02-11T01:52:55.662583Z","end":"2026-02-11T01:52:56.762143Z","steps":["trace[1921011083] 'range keys from in-memory index tree' (duration: 1.098259376s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:56.762185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:55.662568Z","time spent":"1.099607658s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:52:57.351869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.768354Z","time spent":"583.511347ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T01:52:57.809457Z","caller":"traceutil/trace.go:171","msg":"trace[1704145694] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:880; }","duration":"829.313481ms","start":"2026-02-11T01:52:56.980126Z","end":"2026-02-11T01:52:57.809439Z","steps":["trace[1704145694] 'read index received' (duration: 372.26243ms)","trace[1704145694] 'applied index is now lower than readState.Index' (duration: 457.050021ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:57.809879Z","caller":"traceutil/trace.go:171","msg":"trace[1582310518] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"1.041150832s","start":"2026-02-11T01:52:56.768717Z","end":"2026-02-11T01:52:57.809868Z","steps":["trace[1582310518] 'process raft request' (duration: 1.040508477s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.809967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.768704Z","time spent":"1.041207823s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:57.810155Z","caller":"traceutil/trace.go:171","msg":"trace[389176170] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"1.03933825s","start":"2026-02-11T01:52:56.770807Z","end":"2026-02-11T01:52:57.810145Z","steps":["trace[389176170] 'process raft request' (duration: 1.038534461s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.810244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.770798Z","time spent":"1.039384311s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:57.810368Z","caller":"traceutil/trace.go:171","msg":"trace[142945112] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"1.038961271s","start":"2026-02-11T01:52:56.771399Z","end":"2026-02-11T01:52:57.81036Z","steps":["trace[142945112] 'process raft request' (duration: 1.037981918s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.810418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.771393Z","time spent":"1.039000773s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:57.810543Z","caller":"traceutil/trace.go:171","msg":"trace[2102204185] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"1.039102515s","start":"2026-02-11T01:52:56.771433Z","end":"2026-02-11T01:52:57.810535Z","steps":["trace[2102204185] 'process raft request' (duration: 1.037974589s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.810595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.771428Z","time spent":"1.039141336s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:57.810723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.594461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:57.810759Z","caller":"traceutil/trace.go:171","msg":"trace[164612674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"830.650953ms","start":"2026-02-11T01:52:56.980097Z","end":"2026-02-11T01:52:57.810748Z","steps":["trace[164612674] 'agreement among raft nodes before linearized reading' (duration: 830.601622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.810789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:56.980084Z","time spent":"830.697924ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:57.833637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.591218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T01:52:57.833679Z","caller":"traceutil/trace.go:171","msg":"trace[1751042290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"378.67255ms","start":"2026-02-11T01:52:57.454997Z","end":"2026-02-11T01:52:57.833669Z","steps":["trace[1751042290] 'agreement among raft nodes before linearized reading' (duration: 378.577397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.833699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.454984Z","time spent":"378.709161ms","remote":"127.0.0.1:57828","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-11T01:52:57.833746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.307827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:57.833757Z","caller":"traceutil/trace.go:171","msg":"trace[899677276] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:852; }","duration":"718.346298ms","start":"2026-02-11T01:52:57.115407Z","end":"2026-02-11T01:52:57.833754Z","steps":["trace[899677276] 'agreement among raft nodes before linearized reading' (duration: 718.320918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.833768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.115395Z","time spent":"718.370139ms","remote":"127.0.0.1:57904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:52:57.834013Z","caller":"traceutil/trace.go:171","msg":"trace[1805833858] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"481.188095ms","start":"2026-02-11T01:52:57.352819Z","end":"2026-02-11T01:52:57.834007Z","steps":["trace[1805833858] 'process raft request' (duration: 474.036058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.834053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.352802Z","time spent":"481.223747ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:06.078764Z","caller":"traceutil/trace.go:171","msg":"trace[1741790004] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"203.950167ms","start":"2026-02-11T01:53:05.874794Z","end":"2026-02-11T01:53:06.078744Z","steps":["trace[1741790004] 'read index received' (duration: 203.754242ms)","trace[1741790004] 'applied index is now lower than readState.Index' (duration: 195.175µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:06.07902Z","caller":"traceutil/trace.go:171","msg":"trace[2075837404] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"215.935316ms","start":"2026-02-11T01:53:05.863074Z","end":"2026-02-11T01:53:06.079009Z","steps":["trace[2075837404] 'process raft request' (duration: 215.550477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:06.079269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.457328ms","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-11T01:53:06.079308Z","caller":"traceutil/trace.go:171","msg":"trace[1313293359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"204.5265ms","start":"2026-02-11T01:53:05.874772Z","end":"2026-02-11T01:53:06.079299Z","steps":["trace[1313293359] 'agreement among raft nodes before linearized reading' (duration: 204.404397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:06.079472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.579185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T01:53:06.079542Z","caller":"traceutil/trace.go:171","msg":"trace[2066436227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"143.668427ms","start":"2026-02-11T01:53:05.935866Z","end":"2026-02-11T01:53:06.079534Z","steps":["trace[2066436227] 'agreement among raft nodes before linearized reading' (duration: 143.554084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:06.079795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.656524ms","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-11T01:53:06.079832Z","caller":"traceutil/trace.go:171","msg":"trace[1800706477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"142.709905ms","start":"2026-02-11T01:53:05.937114Z","end":"2026-02-11T01:53:06.079823Z","steps":["trace[1800706477] 'agreement among raft nodes before linearized reading' (duration: 142.621413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:06.079995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.325339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:53:06.080027Z","caller":"traceutil/trace.go:171","msg":"trace[1551343350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"143.395981ms","start":"2026-02-11T01:53:05.936624Z","end":"2026-02-11T01:53:06.08002Z","steps":["trace[1551343350] 'agreement among raft nodes before linearized reading' (duration: 143.302149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:06.312383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.767096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:06.312507Z","caller":"traceutil/trace.go:171","msg":"trace[1478670180] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:905; }","duration":"104.084553ms","start":"2026-02-11T01:53:06.208414Z","end":"2026-02-11T01:53:06.312498Z","steps":["trace[1478670180] 'read index received' (duration: 103.309485ms)","trace[1478670180] 'applied index is now lower than readState.Index' (duration: 774.768µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:06.312586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.171215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:53:06.312605Z","caller":"traceutil/trace.go:171","msg":"trace[1517544246] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:874; }","duration":"104.211375ms","start":"2026-02-11T01:53:06.208389Z","end":"2026-02-11T01:53:06.312601Z","steps":["trace[1517544246] 'agreement among raft nodes before linearized reading' (duration: 104.137153ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:06.313083Z","caller":"traceutil/trace.go:171","msg":"trace[1481326571] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"227.587718ms","start":"2026-02-11T01:53:06.085488Z","end":"2026-02-11T01:53:06.313075Z","steps":["trace[1481326571] 'process raft request' (duration: 84.082945ms)","trace[1481326571] 'compare' (duration: 142.320256ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:06.313205Z","caller":"traceutil/trace.go:171","msg":"trace[198231474] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"227.619519ms","start":"2026-02-11T01:53:06.08558Z","end":"2026-02-11T01:53:06.313199Z","steps":["trace[198231474] 'process raft request' (duration: 226.857081ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:06.313319Z","caller":"traceutil/trace.go:171","msg":"trace[1824843082] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"227.695751ms","start":"2026-02-11T01:53:06.085618Z","end":"2026-02-11T01:53:06.313313Z","steps":["trace[1824843082] 'process raft request' (duration: 226.852561ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:06.313427Z","caller":"traceutil/trace.go:171","msg":"trace[736853951] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"226.779349ms","start":"2026-02-11T01:53:06.086642Z","end":"2026-02-11T01:53:06.313421Z","steps":["trace[736853951] 'process raft request' (duration: 225.842547ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:06.512144Z","caller":"traceutil/trace.go:171","msg":"trace[952893190] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"136.092641ms","start":"2026-02-11T01:53:06.376025Z","end":"2026-02-11T01:53:06.512118Z","steps":["trace[952893190] 'process raft request' (duration: 76.860427ms)","trace[952893190] 'compare' (duration: 59.108801ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:08.140136Z","caller":"traceutil/trace.go:171","msg":"trace[1605147359] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"146.045714ms","start":"2026-02-11T01:53:07.994069Z","end":"2026-02-11T01:53:08.140115Z","steps":["trace[1605147359] 'process raft request' (duration: 145.632834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.819717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:09.320293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:09.821206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:10.699818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:09.527057Z","time spent":"1.172748096s","remote":"127.0.0.1:32806","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:53:10.700684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.867936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:10.700739Z","caller":"traceutil/trace.go:171","msg":"trace[803346034] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"2.382009688s","start":"2026-02-11T01:53:08.318719Z","end":"2026-02-11T01:53:10.700729Z","steps":["trace[803346034] 'read index received' (duration: 1.57801918s)","trace[803346034] 'applied index is now lower than readState.Index' (duration: 803.989508ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:10.700864Z","caller":"traceutil/trace.go:171","msg":"trace[1873001771] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"2.55709251s","start":"2026-02-11T01:53:08.143763Z","end":"2026-02-11T01:53:10.700856Z","steps":["trace[1873001771] 'process raft request' (duration: 1.752993559s)","trace[1873001771] 'compare' (duration: 803.377575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:10.700902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.143752Z","time spent":"2.557127831s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:10.701752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.382975651s","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-11T01:53:10.701772Z","caller":"traceutil/trace.go:171","msg":"trace[241929041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"2.383010692s","start":"2026-02-11T01:53:08.318755Z","end":"2026-02-11T01:53:10.701765Z","steps":["trace[241929041] 'agreement among raft nodes before linearized reading' (duration: 2.38295675s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.701787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.318749Z","time spent":"2.383034682s","remote":"127.0.0.1:57828","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-11T01:53:10.701913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.876323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:10.701936Z","caller":"traceutil/trace.go:171","msg":"trace[270168310] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:877; }","duration":"372.931285ms","start":"2026-02-11T01:53:10.328997Z","end":"2026-02-11T01:53:10.701929Z","steps":["trace[270168310] 'agreement among raft nodes before linearized reading' (duration: 372.902384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.70195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.328979Z","time spent":"372.967426ms","remote":"127.0.0.1:39100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:10.701035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.382313615s","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-11T01:53:10.701993Z","caller":"traceutil/trace.go:171","msg":"trace[347008886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"2.383307899s","start":"2026-02-11T01:53:08.318679Z","end":"2026-02-11T01:53:10.701987Z","steps":["trace[347008886] 'agreement among raft nodes before linearized reading' (duration: 2.382290095s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.702018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.31866Z","time spent":"2.383350279s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:53:10.702021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.092207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:10.702033Z","caller":"traceutil/trace.go:171","msg":"trace[1081227290] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:877; }","duration":"859.126458ms","start":"2026-02-11T01:53:09.842903Z","end":"2026-02-11T01:53:10.702029Z","steps":["trace[1081227290] 'agreement among raft nodes before linearized reading' (duration: 859.104757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.702044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:09.84289Z","time spent":"859.151128ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:10.702103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370894227s","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-11T01:53:10.702114Z","caller":"traceutil/trace.go:171","msg":"trace[69638224] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:877; }","duration":"1.370929009s","start":"2026-02-11T01:53:09.331181Z","end":"2026-02-11T01:53:10.702111Z","steps":["trace[69638224] 'agreement among raft nodes before linearized reading' (duration: 1.370905578s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.702128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:09.331169Z","time spent":"1.370954999s","remote":"127.0.0.1:57894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:10.702179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72228838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:10.70219Z","caller":"traceutil/trace.go:171","msg":"trace[645193560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"1.722335192s","start":"2026-02-11T01:53:08.979851Z","end":"2026-02-11T01:53:10.702187Z","steps":["trace[645193560] 'agreement among raft nodes before linearized reading' (duration: 1.722318391s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.702202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.979833Z","time spent":"1.722364992s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:10.70227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.221680761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:10.702283Z","caller":"traceutil/trace.go:171","msg":"trace[369609023] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:877; }","duration":"2.221719953s","start":"2026-02-11T01:53:08.480559Z","end":"2026-02-11T01:53:10.702279Z","steps":["trace[369609023] 'agreement among raft nodes before linearized reading' (duration: 2.221695942s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.702295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.480545Z","time spent":"2.221745903s","remote":"127.0.0.1:57998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:10.702377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381750563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:53:10.702389Z","caller":"traceutil/trace.go:171","msg":"trace[344181741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"2.381844154s","start":"2026-02-11T01:53:08.320541Z","end":"2026-02-11T01:53:10.702385Z","steps":["trace[344181741] 'agreement among raft nodes before linearized reading' (duration: 2.381805843s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.702399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.320536Z","time spent":"2.381861065s","remote":"127.0.0.1:57828","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-11T01:53:10.702467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381941537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T01:53:10.702478Z","caller":"traceutil/trace.go:171","msg":"trace[844853976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"2.381973987s","start":"2026-02-11T01:53:08.320501Z","end":"2026-02-11T01:53:10.702475Z","steps":["trace[844853976] 'agreement among raft nodes before linearized reading' (duration: 2.381942287s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.70249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.32049Z","time spent":"2.381997059s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:53:11.125852Z","caller":"traceutil/trace.go:171","msg":"trace[2059749894] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:914; }","duration":"360.203059ms","start":"2026-02-11T01:53:10.765635Z","end":"2026-02-11T01:53:11.125838Z","steps":["trace[2059749894] 'read index received' (duration: 360.010794ms)","trace[2059749894] 'applied index is now lower than readState.Index' (duration: 191.835µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:11.126097Z","caller":"traceutil/trace.go:171","msg":"trace[67594673] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"416.945485ms","start":"2026-02-11T01:53:10.709145Z","end":"2026-02-11T01:53:11.126091Z","steps":["trace[67594673] 'process raft request' (duration: 416.539015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:11.126154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.70914Z","time spent":"416.971775ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:11.126258Z","caller":"traceutil/trace.go:171","msg":"trace[1505993177] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"413.596536ms","start":"2026-02-11T01:53:10.712655Z","end":"2026-02-11T01:53:11.126252Z","steps":["trace[1505993177] 'process raft request' (duration: 413.152356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:11.126284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.712643Z","time spent":"413.624947ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:11.126341Z","caller":"traceutil/trace.go:171","msg":"trace[747073086] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"413.372841ms","start":"2026-02-11T01:53:10.712964Z","end":"2026-02-11T01:53:11.126337Z","steps":["trace[747073086] 'process raft request' (duration: 412.859709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:11.126362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.712959Z","time spent":"413.391142ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:11.126424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.794402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:11.126438Z","caller":"traceutil/trace.go:171","msg":"trace[740126153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"360.825263ms","start":"2026-02-11T01:53:10.765608Z","end":"2026-02-11T01:53:11.126433Z","steps":["trace[740126153] 'agreement among raft nodes before linearized reading' (duration: 360.801722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:11.126453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.765593Z","time spent":"360.856144ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:53:12.295368Z","caller":"traceutil/trace.go:171","msg":"trace[1011334833] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"157.219414ms","start":"2026-02-11T01:53:12.138134Z","end":"2026-02-11T01:53:12.295353Z","steps":["trace[1011334833] 'read index received' (duration: 157.085111ms)","trace[1011334833] 'applied index is now lower than readState.Index' (duration: 133.603µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:12.295449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.302736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:12.295465Z","caller":"traceutil/trace.go:171","msg":"trace[487590693] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:882; }","duration":"157.358238ms","start":"2026-02-11T01:53:12.138101Z","end":"2026-02-11T01:53:12.295459Z","steps":["trace[487590693] 'agreement among raft nodes before linearized reading' (duration: 157.303786ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:12.295619Z","caller":"traceutil/trace.go:171","msg":"trace[996455321] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"204.602412ms","start":"2026-02-11T01:53:12.090995Z","end":"2026-02-11T01:53:12.295598Z","steps":["trace[996455321] 'process raft request' (duration: 204.241043ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:13.014846Z","caller":"traceutil/trace.go:171","msg":"trace[504482433] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"286.970039ms","start":"2026-02-11T01:53:12.727861Z","end":"2026-02-11T01:53:13.014831Z","steps":["trace[504482433] 'process raft request' (duration: 286.868146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.445278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331057978s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T01:53:14.445353Z","caller":"traceutil/trace.go:171","msg":"trace[733197796] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"1.67886971s","start":"2026-02-11T01:53:12.766465Z","end":"2026-02-11T01:53:14.445334Z","steps":["trace[733197796] 'read index received' (duration: 248.702166ms)","trace[733197796] 'applied index is now lower than readState.Index' (duration: 1.430166664s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:14.44552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679048414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-11T01:53:14.445528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470336251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:14.44555Z","caller":"traceutil/trace.go:171","msg":"trace[1978686165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"1.470388011s","start":"2026-02-11T01:53:12.975156Z","end":"2026-02-11T01:53:14.445544Z","steps":["trace[1978686165] 'agreement among raft nodes before linearized reading' (duration: 1.47034824s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.445566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.975143Z","time spent":"1.470420343s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:14.445569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314792403s","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-11T01:53:14.44558Z","caller":"traceutil/trace.go:171","msg":"trace[281086307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"1.314825044s","start":"2026-02-11T01:53:13.130751Z","end":"2026-02-11T01:53:14.445576Z","steps":["trace[281086307] 'agreement among raft nodes before linearized reading' (duration: 1.314793143s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.445592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:13.130736Z","time spent":"1.314852484s","remote":"127.0.0.1:57828","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-11T01:53:14.44567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.189297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T01:53:14.445676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314903605s","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-11T01:53:14.445683Z","caller":"traceutil/trace.go:171","msg":"trace[165861343] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:883; }","duration":"161.230888ms","start":"2026-02-11T01:53:14.284448Z","end":"2026-02-11T01:53:14.445679Z","steps":["trace[165861343] 'agreement among raft nodes before linearized reading' (duration: 161.208918ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:14.445688Z","caller":"traceutil/trace.go:171","msg":"trace[609713724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"1.314938076s","start":"2026-02-11T01:53:13.130747Z","end":"2026-02-11T01:53:14.445685Z","steps":["trace[609713724] 'agreement among raft nodes before linearized reading' (duration: 1.314899666s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.4457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:13.130732Z","time spent":"1.314965687s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:53:14.445753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.130238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:14.445765Z","caller":"traceutil/trace.go:171","msg":"trace[242169386] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:883; }","duration":"397.166449ms","start":"2026-02-11T01:53:14.048594Z","end":"2026-02-11T01:53:14.445761Z","steps":["trace[242169386] 'agreement among raft nodes before linearized reading' (duration: 397.145119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.445774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316902551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-11T01:53:14.445777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.04858Z","time spent":"397.19343ms","remote":"127.0.0.1:57962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:53:14.445786Z","caller":"traceutil/trace.go:171","msg":"trace[1374683843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"1.316936353s","start":"2026-02-11T01:53:13.128845Z","end":"2026-02-11T01:53:14.445782Z","steps":["trace[1374683843] 'agreement among raft nodes before linearized reading' (duration: 1.316904022s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.445798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:13.128832Z","time spent":"1.316963533s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:53:14.445535Z","caller":"traceutil/trace.go:171","msg":"trace[257616480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"1.679092965s","start":"2026-02-11T01:53:12.766437Z","end":"2026-02-11T01:53:14.44553Z","steps":["trace[257616480] 'agreement among raft nodes before linearized reading' (duration: 1.679008273s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.445868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.766424Z","time spent":"1.679440314s","remote":"127.0.0.1:57828","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-11T01:53:14.954365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541038,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:53:15.197523Z","caller":"traceutil/trace.go:171","msg":"trace[927322839] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"748.410492ms","start":"2026-02-11T01:53:14.449098Z","end":"2026-02-11T01:53:15.197509Z","steps":["trace[927322839] 'process raft request' (duration: 748.316269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:15.197612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.449089Z","time spent":"748.475763ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:16.148624Z","caller":"traceutil/trace.go:171","msg":"trace[277515382] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:921; }","duration":"1.694796717s","start":"2026-02-11T01:53:14.453795Z","end":"2026-02-11T01:53:16.148591Z","steps":["trace[277515382] 'read index received' (duration: 744.067331ms)","trace[277515382] 'applied index is now lower than readState.Index' (duration: 950.728586ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:16.148977Z","caller":"traceutil/trace.go:171","msg":"trace[1129303769] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"1.699138318s","start":"2026-02-11T01:53:14.449827Z","end":"2026-02-11T01:53:16.148965Z","steps":["trace[1129303769] 'process raft request' (duration: 1.698537234s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:16.149084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.449821Z","time spent":"1.699185409s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:16.149251Z","caller":"traceutil/trace.go:171","msg":"trace[577233787] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"1.698889202s","start":"2026-02-11T01:53:14.450353Z","end":"2026-02-11T01:53:16.149242Z","steps":["trace[577233787] 'process raft request' (duration: 1.698153305s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:16.149298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.450348Z","time spent":"1.698924623s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:16.149425Z","caller":"traceutil/trace.go:171","msg":"trace[1740317004] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"1.696421715s","start":"2026-02-11T01:53:14.452993Z","end":"2026-02-11T01:53:16.149414Z","steps":["trace[1740317004] 'process raft request' (duration: 1.695550395s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:16.149471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.452986Z","time spent":"1.696460686s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:16.149595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695796391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:16.150215Z","caller":"traceutil/trace.go:171","msg":"trace[1469200549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"1.696423895s","start":"2026-02-11T01:53:14.453779Z","end":"2026-02-11T01:53:16.150203Z","steps":["trace[1469200549] 'agreement among raft nodes before linearized reading' (duration: 1.695787481s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:16.150265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.453774Z","time spent":"1.696481407s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:16.150481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131703961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:53:16.150509Z","caller":"traceutil/trace.go:171","msg":"trace[2141375696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"1.131762572s","start":"2026-02-11T01:53:15.018738Z","end":"2026-02-11T01:53:16.1505Z","steps":["trace[2141375696] 'agreement among raft nodes before linearized reading' (duration: 1.13166069s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:16.150534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:15.018722Z","time spent":"1.131805993s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:16.661637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:17.59754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.334844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:17.597619Z","caller":"traceutil/trace.go:171","msg":"trace[2141783744] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"1.436693855s","start":"2026-02-11T01:53:16.160914Z","end":"2026-02-11T01:53:17.597608Z","steps":["trace[2141783744] 'read index received' (duration: 954.272649ms)","trace[2141783744] 'applied index is now lower than readState.Index' (duration: 482.420416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:17.599169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400014489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:17.599199Z","caller":"traceutil/trace.go:171","msg":"trace[48498819] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:888; }","duration":"1.400077761s","start":"2026-02-11T01:53:16.199111Z","end":"2026-02-11T01:53:17.599189Z","steps":["trace[48498819] 'agreement among raft nodes before linearized reading' (duration: 1.400016689s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:17.599223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.199074Z","time spent":"1.400142771s","remote":"127.0.0.1:58036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:17.599382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438440425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:17.599404Z","caller":"traceutil/trace.go:171","msg":"trace[947345155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"1.438497016s","start":"2026-02-11T01:53:16.160899Z","end":"2026-02-11T01:53:17.599396Z","steps":["trace[947345155] 'agreement among raft nodes before linearized reading' (duration: 1.438443145s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:17.599423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.160893Z","time spent":"1.438525397s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:53:17.599681Z","caller":"traceutil/trace.go:171","msg":"trace[606746080] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"1.441381343s","start":"2026-02-11T01:53:16.158278Z","end":"2026-02-11T01:53:17.599659Z","steps":["trace[606746080] 'process raft request' (duration: 956.870618ms)","trace[606746080] 'compare' (duration: 481.854553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:17.599729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.158268Z","time spent":"1.441436315s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:17.600016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391441652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:53:17.600039Z","caller":"traceutil/trace.go:171","msg":"trace[1490594513] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:888; }","duration":"1.391477652s","start":"2026-02-11T01:53:16.208554Z","end":"2026-02-11T01:53:17.600032Z","steps":["trace[1490594513] 'agreement among raft nodes before linearized reading' (duration: 1.39139374s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:17.600056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.208548Z","time spent":"1.391504393s","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-02-11T01:53:17.600178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.901486ms","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-11T01:53:17.600196Z","caller":"traceutil/trace.go:171","msg":"trace[1753602394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"397.945598ms","start":"2026-02-11T01:53:17.202245Z","end":"2026-02-11T01:53:17.60019Z","steps":["trace[1753602394] 'agreement among raft nodes before linearized reading' (duration: 397.897487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:17.600212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:17.202224Z","time spent":"397.984908ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:53:17.600289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119022468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:17.600305Z","caller":"traceutil/trace.go:171","msg":"trace[1284581549] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:888; }","duration":"1.119063669s","start":"2026-02-11T01:53:16.481236Z","end":"2026-02-11T01:53:17.6003Z","steps":["trace[1284581549] 'agreement among raft nodes before linearized reading' (duration: 1.119035369s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:17.600324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.481223Z","time spent":"1.119097s","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:17.888664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.825916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:17.889374Z","caller":"traceutil/trace.go:171","msg":"trace[758759566] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"213.890852ms","start":"2026-02-11T01:53:17.675447Z","end":"2026-02-11T01:53:17.889338Z","steps":["trace[758759566] 'read index received' (duration: 77.979029ms)","trace[758759566] 'applied index is now lower than readState.Index' (duration: 135.910493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:17.889794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.340603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:17.889815Z","caller":"traceutil/trace.go:171","msg":"trace[1660269425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"214.389093ms","start":"2026-02-11T01:53:17.675418Z","end":"2026-02-11T01:53:17.889807Z","steps":["trace[1660269425] 'agreement among raft nodes before linearized reading' (duration: 213.976904ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:17.89203Z","caller":"traceutil/trace.go:171","msg":"trace[310511652] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"224.979758ms","start":"2026-02-11T01:53:17.667032Z","end":"2026-02-11T01:53:17.892011Z","steps":["trace[310511652] 'process raft request' (duration: 86.368252ms)","trace[310511652] 'compare' (duration: 133.624061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:19.087818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.811198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:19.087862Z","caller":"traceutil/trace.go:171","msg":"trace[2029532362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"111.88543ms","start":"2026-02-11T01:53:18.975965Z","end":"2026-02-11T01:53:19.087851Z","steps":["trace[2029532362] 'range keys from in-memory index tree' (duration: 111.744347ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:19.774869Z","caller":"traceutil/trace.go:171","msg":"trace[469323279] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"138.411362ms","start":"2026-02-11T01:53:19.636444Z","end":"2026-02-11T01:53:19.774856Z","steps":["trace[469323279] 'process raft request' (duration: 138.100715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.331525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.125403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:20.33158Z","caller":"traceutil/trace.go:171","msg":"trace[1254728920] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"355.383186ms","start":"2026-02-11T01:53:19.976188Z","end":"2026-02-11T01:53:20.331571Z","steps":["trace[1254728920] 'read index received' (duration: 169.11514ms)","trace[1254728920] 'applied index is now lower than readState.Index' (duration: 186.267456ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:20.33168Z","caller":"traceutil/trace.go:171","msg":"trace[1374122139] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"554.050059ms","start":"2026-02-11T01:53:19.777624Z","end":"2026-02-11T01:53:20.331674Z","steps":["trace[1374122139] 'process raft request' (duration: 367.718491ms)","trace[1374122139] 'compare' (duration: 186.071432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:20.331708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.777612Z","time spent":"554.080489ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:20.331793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.611101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:20.331806Z","caller":"traceutil/trace.go:171","msg":"trace[1174474066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"355.653973ms","start":"2026-02-11T01:53:19.976147Z","end":"2026-02-11T01:53:20.331801Z","steps":["trace[1174474066] 'agreement among raft nodes before linearized reading' (duration: 355.632592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.331817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.976135Z","time spent":"355.679743ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:20.331916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.011699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:53:20.331928Z","caller":"traceutil/trace.go:171","msg":"trace[1317517302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"153.023459ms","start":"2026-02-11T01:53:20.178901Z","end":"2026-02-11T01:53:20.331924Z","steps":["trace[1317517302] 'agreement among raft nodes before linearized reading' (duration: 152.973618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.332099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.147847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T01:53:20.332113Z","caller":"traceutil/trace.go:171","msg":"trace[1505271134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"109.175428ms","start":"2026-02-11T01:53:20.222934Z","end":"2026-02-11T01:53:20.33211Z","steps":["trace[1505271134] 'agreement among raft nodes before linearized reading' (duration: 109.138427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:21.479301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541075,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:21.903457Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001438427s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:53:21.905735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003592737s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:21.907824Z","caller":"traceutil/trace.go:171","msg":"trace[401119886] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.570916771s","start":"2026-02-11T01:53:20.336878Z","end":"2026-02-11T01:53:21.907795Z","steps":["trace[401119886] 'process raft request' (duration: 565.190555ms)","trace[401119886] 'compare' (duration: 1.003352831s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:21.908196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.336868Z","time spent":"1.571223798s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:21.908647Z","caller":"traceutil/trace.go:171","msg":"trace[618886880] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.546322745s","start":"2026-02-11T01:53:20.36231Z","end":"2026-02-11T01:53:21.908632Z","steps":["trace[618886880] 'process raft request' (duration: 1.54351478s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:21.908834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.362297Z","time spent":"1.546497838s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:21.909051Z","caller":"traceutil/trace.go:171","msg":"trace[827792374] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.546686743s","start":"2026-02-11T01:53:20.362353Z","end":"2026-02-11T01:53:21.909039Z","steps":["trace[827792374] 'process raft request' (duration: 1.54354224s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:21.909177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.362349Z","time spent":"1.546788955s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:22.919099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013151819s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T01:53:22.919252Z","caller":"traceutil/trace.go:171","msg":"trace[1488493807] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:934; }","duration":"1.940293492s","start":"2026-02-11T01:53:20.978947Z","end":"2026-02-11T01:53:22.91924Z","steps":["trace[1488493807] 'read index received' (duration: 924.742079ms)","trace[1488493807] 'applied index is now lower than readState.Index' (duration: 1.015550493s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:22.919325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940372295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:22.919338Z","caller":"traceutil/trace.go:171","msg":"trace[1056508755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"1.940423136s","start":"2026-02-11T01:53:20.97891Z","end":"2026-02-11T01:53:22.919333Z","steps":["trace[1056508755] 'agreement among raft nodes before linearized reading' (duration: 1.940391685s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.919356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.978893Z","time spent":"1.940459747s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:53:22.919627Z","caller":"traceutil/trace.go:171","msg":"trace[816484338] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"453.234705ms","start":"2026-02-11T01:53:22.46638Z","end":"2026-02-11T01:53:22.919614Z","steps":["trace[816484338] 'process raft request' (duration: 452.789095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.91968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.466359Z","time spent":"453.284656ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:23.656113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.641525906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:23.656209Z","caller":"traceutil/trace.go:171","msg":"trace[327011869] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:900; }","duration":"2.64166112s","start":"2026-02-11T01:53:21.014525Z","end":"2026-02-11T01:53:23.656186Z","steps":["trace[327011869] 'agreement among raft nodes before linearized reading' (duration: 1.905080141s)","trace[327011869] 'count revisions from in-memory index tree' (duration: 736.465976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:23.656253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:21.014507Z","time spent":"2.641734981s","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:23.656547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.228336ms","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-11T01:53:23.656582Z","caller":"traceutil/trace.go:171","msg":"trace[792744030] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:900; }","duration":"943.262107ms","start":"2026-02-11T01:53:22.713308Z","end":"2026-02-11T01:53:23.65657Z","steps":["trace[792744030] 'agreement among raft nodes before linearized reading' (duration: 206.444012ms)","trace[792744030] 'count revisions from in-memory index tree' (duration: 736.776114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:23.656613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.713241Z","time spent":"943.364009ms","remote":"127.0.0.1:57880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:23.656749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011212084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:23.656777Z","caller":"traceutil/trace.go:171","msg":"trace[778141101] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:900; }","duration":"1.011272815s","start":"2026-02-11T01:53:22.645495Z","end":"2026-02-11T01:53:23.656768Z","steps":["trace[778141101] 'agreement among raft nodes before linearized reading' (duration: 274.263076ms)","trace[778141101] 'count revisions from in-memory index tree' (duration: 736.972738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:23.656806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.645477Z","time spent":"1.011321696s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:23.65699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294860347s","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-11T01:53:23.657018Z","caller":"traceutil/trace.go:171","msg":"trace[921479504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"1.294910838s","start":"2026-02-11T01:53:22.362098Z","end":"2026-02-11T01:53:23.657009Z","steps":["trace[921479504] 'agreement among raft nodes before linearized reading' (duration: 557.665803ms)","trace[921479504] 'range keys from in-memory index tree' (duration: 737.152642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:23.657045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.362084Z","time spent":"1.294954789s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:53:23.657204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878632003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:53:23.657233Z","caller":"traceutil/trace.go:171","msg":"trace[777752643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"1.878691884s","start":"2026-02-11T01:53:21.778532Z","end":"2026-02-11T01:53:23.657224Z","steps":["trace[777752643] 'agreement among raft nodes before linearized reading' (duration: 1.141239135s)","trace[777752643] 'range keys from in-memory index tree' (duration: 737.379737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:23.65726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:21.778513Z","time spent":"1.878741225s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:23.657375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.684918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:23.657402Z","caller":"traceutil/trace.go:171","msg":"trace[38243013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"735.72628ms","start":"2026-02-11T01:53:22.921666Z","end":"2026-02-11T01:53:23.657393Z","steps":["trace[38243013] 'range keys from in-memory index tree' (duration: 735.658098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:23.657427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.921657Z","time spent":"735.76507ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:23.657662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.98606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:23.657692Z","caller":"traceutil/trace.go:171","msg":"trace[1165274574] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:900; }","duration":"496.058471ms","start":"2026-02-11T01:53:23.161625Z","end":"2026-02-11T01:53:23.657684Z","steps":["trace[1165274574] 'count revisions from in-memory index tree' (duration: 495.909498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:23.657719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:23.161606Z","time spent":"496.105773ms","remote":"127.0.0.1:39068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:53:23.995554Z","caller":"traceutil/trace.go:171","msg":"trace[131976675] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"327.941403ms","start":"2026-02-11T01:53:23.667591Z","end":"2026-02-11T01:53:23.995532Z","steps":["trace[131976675] 'process raft request' (duration: 302.713551ms)","trace[131976675] 'compare' (duration: 24.775912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:23.995676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:23.667587Z","time spent":"328.023705ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:24.406986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.294091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:24.407082Z","caller":"traceutil/trace.go:171","msg":"trace[942117370] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"402.437941ms","start":"2026-02-11T01:53:24.004627Z","end":"2026-02-11T01:53:24.407065Z","steps":["trace[942117370] 'process raft request' (duration: 109.994597ms)","trace[942117370] 'compare' (duration: 292.070316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:24.407139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:24.004607Z","time spent":"402.505223ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:24.431461Z","caller":"traceutil/trace.go:171","msg":"trace[420327071] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"426.593439ms","start":"2026-02-11T01:53:24.004858Z","end":"2026-02-11T01:53:24.431451Z","steps":["trace[420327071] 'process raft request' (duration: 426.505547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:24.431535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:24.004849Z","time spent":"426.6427ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:24.431606Z","caller":"traceutil/trace.go:171","msg":"trace[925755401] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"347.208987ms","start":"2026-02-11T01:53:24.084391Z","end":"2026-02-11T01:53:24.4316Z","steps":["trace[925755401] 'process raft request' (duration: 347.036063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:24.431636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:24.084373Z","time spent":"347.249048ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:26.262988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.87916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:53:26.263046Z","caller":"traceutil/trace.go:171","msg":"trace[791113307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"261.974821ms","start":"2026-02-11T01:53:26.001059Z","end":"2026-02-11T01:53:26.263034Z","steps":["trace[791113307] 'range keys from in-memory index tree' (duration: 261.761077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:26.912609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737477489449541110,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:27.014294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.264277Z","time spent":"750.011531ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:53:27.424056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.58631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:27.424196Z","caller":"traceutil/trace.go:171","msg":"trace[1289051007] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"408.88661ms","start":"2026-02-11T01:53:27.015296Z","end":"2026-02-11T01:53:27.424183Z","steps":["trace[1289051007] 'process raft request' (duration: 408.828888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.424243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:27.015276Z","time spent":"408.944041ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.424439Z","caller":"traceutil/trace.go:171","msg":"trace[1839671204] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.157346224s","start":"2026-02-11T01:53:26.267085Z","end":"2026-02-11T01:53:27.424431Z","steps":["trace[1839671204] 'process raft request' (duration: 951.330834ms)","trace[1839671204] 'compare' (duration: 205.504629ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:27.424493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.267073Z","time spent":"1.157398416s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.424573Z","caller":"traceutil/trace.go:171","msg":"trace[1547936141] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"1.012163576s","start":"2026-02-11T01:53:26.412401Z","end":"2026-02-11T01:53:27.424565Z","steps":["trace[1547936141] 'read index received' (duration: 601.715281ms)","trace[1547936141] 'applied index is now lower than readState.Index' (duration: 410.447635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:27.424712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012373232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:53:27.42473Z","caller":"traceutil/trace.go:171","msg":"trace[1960842236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"1.012438403s","start":"2026-02-11T01:53:26.412286Z","end":"2026-02-11T01:53:27.424724Z","steps":["trace[1960842236] 'agreement among raft nodes before linearized reading' (duration: 1.012363531s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.42475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.412262Z","time spent":"1.012483823s","remote":"127.0.0.1:57828","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-11T01:53:27.424857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.750353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T01:53:27.424874Z","caller":"traceutil/trace.go:171","msg":"trace[65525643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"990.781853ms","start":"2026-02-11T01:53:26.434087Z","end":"2026-02-11T01:53:27.424869Z","steps":["trace[65525643] 'agreement among raft nodes before linearized reading' (duration: 990.735762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.424889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.434078Z","time spent":"990.808204ms","remote":"127.0.0.1:57828","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-11T01:53:27.426628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.428868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:27.426652Z","caller":"traceutil/trace.go:171","msg":"trace[2132936080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"450.481719ms","start":"2026-02-11T01:53:26.976163Z","end":"2026-02-11T01:53:27.426645Z","steps":["trace[2132936080] 'agreement among raft nodes before linearized reading' (duration: 450.441048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.426671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.97615Z","time spent":"450.51697ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:27.426885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.249854ms","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-11T01:53:27.426904Z","caller":"traceutil/trace.go:171","msg":"trace[1951866756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"991.295845ms","start":"2026-02-11T01:53:26.435602Z","end":"2026-02-11T01:53:27.426898Z","steps":["trace[1951866756] 'agreement among raft nodes before linearized reading' (duration: 991.239464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.426921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.435589Z","time spent":"991.327985ms","remote":"127.0.0.1:57828","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-11T01:53:27.76567Z","caller":"traceutil/trace.go:171","msg":"trace[932979446] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"332.494318ms","start":"2026-02-11T01:53:27.433154Z","end":"2026-02-11T01:53:27.765649Z","steps":["trace[932979446] 'process raft request' (duration: 332.377256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.765993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:27.433143Z","time spent":"332.625631ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.767146Z","caller":"traceutil/trace.go:171","msg":"trace[1172421895] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:958; }","duration":"333.099512ms","start":"2026-02-11T01:53:27.434036Z","end":"2026-02-11T01:53:27.767136Z","steps":["trace[1172421895] 'read index received' (duration: 333.095432ms)","trace[1172421895] 'applied index is now lower than readState.Index' (duration: 3.19µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:27.767269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.225495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:53:27.767289Z","caller":"traceutil/trace.go:171","msg":"trace[1222374113] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:917; }","duration":"333.260996ms","start":"2026-02-11T01:53:27.434022Z","end":"2026-02-11T01:53:27.767283Z","steps":["trace[1222374113] 'agreement among raft nodes before linearized reading' (duration: 333.169784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.767308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:27.434016Z","time spent":"333.288027ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-11T01:53:27.767757Z","caller":"traceutil/trace.go:171","msg":"trace[368207141] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"332.694113ms","start":"2026-02-11T01:53:27.435053Z","end":"2026-02-11T01:53:27.767748Z","steps":["trace[368207141] 'process raft request' (duration: 332.405006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.767799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:27.435046Z","time spent":"332.731054ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.767863Z","caller":"traceutil/trace.go:171","msg":"trace[1002798590] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"332.260023ms","start":"2026-02-11T01:53:27.435583Z","end":"2026-02-11T01:53:27.767843Z","steps":["trace[1002798590] 'process raft request' (duration: 331.909925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.7679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:27.435576Z","time spent":"332.302914ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:28.930975Z","caller":"traceutil/trace.go:171","msg":"trace[1701191060] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"156.683164ms","start":"2026-02-11T01:53:28.774276Z","end":"2026-02-11T01:53:28.930959Z","steps":["trace[1701191060] 'process raft request' (duration: 156.572031ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:29.928149Z","caller":"traceutil/trace.go:171","msg":"trace[244815860] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"142.732912ms","start":"2026-02-11T01:53:29.7854Z","end":"2026-02-11T01:53:29.928133Z","steps":["trace[244815860] 'process raft request' (duration: 142.697491ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:29.928312Z","caller":"traceutil/trace.go:171","msg":"trace[1137434714] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"142.929197ms","start":"2026-02-11T01:53:29.785363Z","end":"2026-02-11T01:53:29.928292Z","steps":["trace[1137434714] 'process raft request' (duration: 74.290794ms)","trace[1137434714] 'compare' (duration: 68.353726ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:34.022807Z","caller":"traceutil/trace.go:171","msg":"trace[1894545903] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"146.007278ms","start":"2026-02-11T01:53:33.876783Z","end":"2026-02-11T01:53:34.02279Z","steps":["trace[1894545903] 'process raft request' (duration: 91.790567ms)","trace[1894545903] 'compare' (duration: 53.817521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:36.359539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.562259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:53:36.359593Z","caller":"traceutil/trace.go:171","msg":"trace[176330319] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:968; }","duration":"149.658331ms","start":"2026-02-11T01:53:36.209921Z","end":"2026-02-11T01:53:36.35958Z","steps":["trace[176330319] 'range keys from in-memory index tree' (duration: 149.388565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:36.359869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.92554ms","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-11T01:53:36.359963Z","caller":"traceutil/trace.go:171","msg":"trace[1063769893] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:968; }","duration":"128.028063ms","start":"2026-02-11T01:53:36.231922Z","end":"2026-02-11T01:53:36.35995Z","steps":["trace[1063769893] 'range keys from in-memory index tree' (duration: 127.91289ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:43.707277Z","caller":"traceutil/trace.go:171","msg":"trace[47504617] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"277.091832ms","start":"2026-02-11T01:53:43.430164Z","end":"2026-02-11T01:53:43.707256Z","steps":["trace[47504617] 'read index received' (duration: 276.886637ms)","trace[47504617] 'applied index is now lower than readState.Index' (duration: 204.205µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:43.708669Z","caller":"traceutil/trace.go:171","msg":"trace[309897349] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"384.266243ms","start":"2026-02-11T01:53:43.324357Z","end":"2026-02-11T01:53:43.708624Z","steps":["trace[309897349] 'process raft request' (duration: 382.747488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:43.709089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:43.324335Z","time spent":"384.379976ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:43.709719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.524928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-11T01:53:43.709767Z","caller":"traceutil/trace.go:171","msg":"trace[781990746] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1017; }","duration":"279.61415ms","start":"2026-02-11T01:53:43.430139Z","end":"2026-02-11T01:53:43.709753Z","steps":["trace[781990746] 'agreement among raft nodes before linearized reading' (duration: 279.432476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:43.710693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.639223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:53:43.710751Z","caller":"traceutil/trace.go:171","msg":"trace[1891021571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"185.748005ms","start":"2026-02-11T01:53:43.524993Z","end":"2026-02-11T01:53:43.710741Z","steps":["trace[1891021571] 'agreement among raft nodes before linearized reading' (duration: 185.599352ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:44.117928Z","caller":"traceutil/trace.go:171","msg":"trace[1167619940] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"401.473641ms","start":"2026-02-11T01:53:43.716424Z","end":"2026-02-11T01:53:44.117898Z","steps":["trace[1167619940] 'process raft request' (duration: 343.689947ms)","trace[1167619940] 'compare' (duration: 57.322263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:44.118083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:43.716411Z","time spent":"401.591253ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:44.148506Z","caller":"traceutil/trace.go:171","msg":"trace[120318806] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"429.881796ms","start":"2026-02-11T01:53:43.718603Z","end":"2026-02-11T01:53:44.148484Z","steps":["trace[120318806] 'process raft request' (duration: 428.777671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:44.148655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:43.718594Z","time spent":"429.977678ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:44.149354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.313232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:53:44.149411Z","caller":"traceutil/trace.go:171","msg":"trace[1810430683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"243.423135ms","start":"2026-02-11T01:53:43.905973Z","end":"2026-02-11T01:53:44.149396Z","steps":["trace[1810430683] 'agreement among raft nodes before linearized reading' (duration: 243.236631ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:44.148225Z","caller":"traceutil/trace.go:171","msg":"trace[860902227] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"242.170796ms","start":"2026-02-11T01:53:43.906023Z","end":"2026-02-11T01:53:44.148194Z","steps":["trace[860902227] 'read index received' (duration: 154.073914ms)","trace[860902227] 'applied index is now lower than readState.Index' (duration: 88.095382ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:58.456519Z","caller":"traceutil/trace.go:171","msg":"trace[455590423] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"115.637227ms","start":"2026-02-11T01:53:58.340849Z","end":"2026-02-11T01:53:58.456486Z","steps":["trace[455590423] 'process raft request' (duration: 47.28594ms)","trace[455590423] 'compare' (duration: 68.191533ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:56:56.145743Z","caller":"traceutil/trace.go:171","msg":"trace[843150857] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"108.913467ms","start":"2026-02-11T01:56:56.036775Z","end":"2026-02-11T01:56:56.145689Z","steps":["trace[843150857] 'process raft request' (duration: 108.62587ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:06.480545Z","caller":"traceutil/trace.go:171","msg":"trace[1710072] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"174.564178ms","start":"2026-02-11T01:57:06.305766Z","end":"2026-02-11T01:57:06.48033Z","steps":["trace[1710072] 'process raft request' (duration: 174.340032ms)"],"step_count":1}